0001067: [BFX-AT100][BTF] Issue that the TV turns on after SW updates in the sleep state (BTVQ-17456)

ID	Project	Category	View Status	Date Submitted	Last Update

0001067	T99X171.00 SKB Eagle	SW Issue	public	2021-01-05 09:58	2021-06-23 15:11

Reporter	(ALTech) Younkwang Jung	Assigned To	(ALTech) Younkwang Jung	Due Date	2021-01-07 10:42
Priority	high	Severity	s2-severe	Reproducibility	always
Status	closed	Resolution	fixed

Summary	0001067: [BFX-AT100][BTF] Issue that the TV turns on after SW updates in the sleep state (BTVQ-17456)
Description	Hi Kerwin Issue that the TV turns on after SW updates in the sleep state was reported in BMT test (BTVQ-17456) The method of reproduction is * Environment - upload 42 versions of SW on OTA. - STB SW is 41 version. 1) Set the STB in the sleep state by using "B tv 전원" 2) After 15 minutes, the FW update is done in the sleep state. and reboot the STB after completing the FW update in recovery mode. 3) The TV turns on at boot time after the FW update. 4) After the various initializations of the STB at MiddleWare are completed, the STB enter in sleep state. The issue here is number 3). The TV is turning on even though there is no hdmi output on TVs that support CEC of course, no problem with TVs that do not support CEC I'm checking it now, but looking at the code, I'm a little suspicious about this part(init.amlogic.board.rc). it don't have a CEC control code. this is just my guess. init.amlogic.board.rc #Foxcomm modified start, Support reboot to system reset mode , JerryLin 2019/02/15 on property:ro.boot.enableSystemReset=1 write /sys/class/amhdmitx/amhdmitx0/phy 0 write /sys/class/amhdmitx/amhdmitx0/avmute 2 setprop debug.sf.nobootanimation 1 setprop sys.skb.auto_reset true setprop sys.mode.auto_reset true If you tell me how to fix this issue or debug it, I will test it and let you know the result. Please contact me if you have questions. Thank you for your support YK.Jung
Tags	No tags attached.
Attach Tags	(Separate by ",")

User List	~~(ALTech) Jason Park~~ , (ALTech) SY Yoon Username

(SW) Kerwin Chen 2021-01-05 15:15 developer ~0005714	Hi Jerry, Please work with Mr. YK.Jung to debug the issue. Thanks !

(SW) Jerry Lin 2021-01-05 19:43 developer ~0005719	Hi Mr. YK.Jung, 1. Please share me the URAT log. I need to confirm whether the system_reset mode is entered after the update is completed. 2. Item 2 need to use "reboot private_recovery" command to upgrade fw. please help to check it. 3. Please share /cache/recovery files to me. Jerry

(ALTech) Younkwang Jung 2021-01-06 09:05 developer ~0005720	Hi Jerry I have attached the log file. (2021-01-06_sleep_update_test.log) , please check it I think , you can check it 1,2 items and 3) I'll send you the file when it's ready. Thank you for your support YK.Jung 2021-01-06_sleep_update_test.log (157,159 bytes) [01/06, 09:25:22] console:/ $ [01/06, 09:25:23] console:/ $ [01/06, 09:25:23] console:/ $ [01/06, 09:25:23] console:/ $ [01/06, 09:25:23] console:/ $ [01/06, 09:25:23] console:/ $ [01/06, 09:26:49] console:/ $ [01/06, 09:26:49] console:/ $ [01/06, 09:26:49] console:/ $ [01/06, 09:27:06] console:/ $ [01/06, 09:27:06] console:/ $ [01/06, 09:27:06] console:/ $ [01/06, 09:27:25] console:/ $ ALT comment : enter sleep state [01/06, 09:27:25] /system/bin/sh: ALT: inaccessible or not found [01/06, 09:28:38] 127\|console:/ $ [01/06, 09:28:38] 127\|console:/ $ [01/06, 09:28:38] 127\|console:/ $ [01/06, 09:28:38] 127\|console:/ $ [01/06, 09:28:38] 127\|console:/ $ [01/06, 09:28:38] 127\|console:/ $ [01/06, 09:28:38] 127\|console:/ $ [01/06, 09:28:39] 127\|console:/ $ [01/06, 09:28:39] 127\|console:/ $ [01/06, 09:28:39] 127\|console:/ $ [01/06, 09:28:39] 127\|console:/ $ [01/06, 09:28:39] 127\|console:/ $ [01/06, 09:35:44] 127\|console:/ $ [01/06, 09:35:44] 127\|console:/ $ [01/06, 09:35:48] 127\|console:/ $ [01/06, 09:35:48] 127\|console:/ $ [01/06, 09:40:13] 127\|console:/ $ ifconfig [01/06, 09:40:13] ifconfig: socket 2 0: Permission denied [01/06, 09:40:14] 1\|console:/ $ su [01/06, 09:40:15] console:/ # ifconfig [01/06, 09:40:16] eth0 Link encap:Ethernet HWaddr ec:5c:68:1d:bc:cc Driver meson6-dwmac [01/06, 09:40:16] inet addr:192.168.200.31 Bcast:192.168.200.255 Mask:255.255.255.0 [01/06, 09:40:16] inet6 addr: fe80::ee5c:68ff:fe1d:bccc/64 Scope: Link [01/06, 09:40:16] UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 [01/06, 09:40:16] RX packets:786927 errors:0 dropped:0 overruns:0 frame:0 [01/06, 09:40:16] TX packets:12650 errors:0 dropped:0 overruns:0 carrier:0 [01/06, 09:40:16] collisions:0 txqueuelen:1000 [01/06, 09:40:16] RX bytes:412946419 TX bytes:1444030 [01/06, 09:40:16] Interrupt:14 [01/06, 09:40:16] [01/06, 09:40:16] lo Link encap:Local Loopback [01/06, 09:40:16] inet addr:127.0.0.1 Mask:255.0.0.0 [01/06, 09:40:16] inet6 addr: ::1/128 Scope: Host [01/06, 09:40:16] UP LOOPBACK RUNNING MTU:65536 Metric:1 [01/06, 09:40:16] RX packets:57 errors:0 dropped:0 overruns:0 frame:0 [01/06, 09:40:16] TX packets:57 errors:0 dropped:0 overruns:0 carrier:0 [01/06, 09:40:16] collisions:0 txqueuelen:1 [01/06, 09:40:16] RX bytes:6089 TX bytes:6089 [01/06, 09:40:16] [01/06, 09:40:17] console:/ # [01/06, 09:40:17] console:/ # [01/06, 09:40:17] console:/ # [01/06, 09:40:17] console:/ # [01/06, 09:40:32] console:/ # [01/06, 09:40:32] console:/ # [01/06, 09:40:32] console:/ # [01/06, 09:40:32] console:/ # [01/06, 09:43:32] console:/ # [01/06, 09:43:38] 143\|console:/ # [ 1204.219200@1] sysrq: SysRq : Show backtrace of all active CPUs [01/06, 09:43:38] [ 1204.220563@1] sysrq: SysRq : Show Blocked State [01/06, 09:43:38] [ 1204.226405@1] sysrq: SysRq : Kill All Tasks [01/06, 09:43:38] [ 1204.236677@1] ueventd: 38 output lines suppressed due to ratelimiting [01/06, 09:43:38] [ 1204.240300@0] video_composer_release [01/06, 09:43:38] [ 1204.241348@2] kworker/2:0: 39 output lines suppressed due to ratelimiting [01/06, 09:43:38] [ 1204.275563@0] binder: undelivered death notification, 00000000e9924d80 [01/06, 09:43:38] [ 1204.276560@0] binder: release 3535:3535 transaction 461210 out, still active [01/06, 09:43:38] [ 1204.283768@0] binder: undelivered TRANSACTION_COMPLETE [01/06, 09:43:38] [ 1204.332643@0] sysrq: SysRq : Show backtrace of all active CPUs [01/06, 09:43:38] [ 1204.333016@0] sysrq: SysRq : Show Blocked State [01/06, 09:43:38] [ 1204.337363@1] task PC stack pid father [01/06, 09:43:38] [ 1204.343602@1] composer@2.3-se D 0 3515 1 0x0040001d [01/06, 09:43:38] [ 1204.349072@1] Call trace: [01/06, 09:43:38] [ 1204.351655@1] [ffffff80203db8f0+ 32][<ffffff8009086ea8>] __switch_to+0xa0/0xc8 [01/06, 09:43:38] [ 1204.358996@1] [ffffff80203db910+ 112][<ffffff8009ec1d9c>] __schedule+0x29c/0x7f8 [01/06, 09:43:38] [ 1204.366322@1] [ffffff80203db980+ 32][<ffffff8009ec2334>] schedule+0x3c/0xa0 [01/06, 09:43:38] [ 1204.373324@1] [ffffff80203db9a0+ 176][<ffffff8009ec57ac>] schedule_timeout+0x1d4/0x428 [01/06, 09:43:38] [ 1204.381208@1] [ffffff80203dba50+ 144][<ffffff8009ec3014>] wait_for_common+0xd4/0x170 [01/06, 09:43:38] [ 1204.388931@1] [ffffff80203dbae0+ 32][<ffffff8009ec30d8>] wait_for_completion+0x28/0x38 [01/06, 09:43:38] [ 1204.396896@1] [ffffff80203dbb00+ 32][<ffffff80090d3c7c>] kthread_stop+0x6c/0x1f0 [01/06, 09:43:38] [ 1204.401167@0] video_composer_release [01/06, 09:43:38] [ 1204.407902@1] [ffffff80203dbb20+ 64][<ffffff8009a94c2c>] video_composer_release+0x5c/0xf8 [01/06, 09:43:38] [ 1204.416140@1] [ffffff80203dbb60+ 80][<ffffff800923a36c>] __fput+0xac/0x1f0 [01/06, 09:43:38] [ 1204.423066@1] [ffffff80203dbbb0+ 32][<ffffff800923a528>] ____fput+0x20/0x30 [01/06, 09:43:38] [ 1204.430089@1] [ffffff80203dbbd0+ 64][<ffffff80090d0f04>] task_work_run+0xcc/0x100 [01/06, 09:43:38] [ 1204.437630@1] [ffffff80203dbc10+ 112][<ffffff80090afe80>] do_exit+0x300/0xa38 [01/06, 09:43:38] [ 1204.444736@1] [ffffff80203dbc80+ 48][<ffffff80090b0644>] do_group_exit+0x3c/0xa0 [01/06, 09:43:38] [ 1204.452188@1] [ffffff80203dbcb0+ 112][<ffffff80090be1c0>] get_signal+0xd8/0x6d0 [01/06, 09:43:38] [ 1204.459474@1] [ffffff80203dbd20+ 336][<ffffff800908aa28>] do_signal+0x180/0x15c8 [01/06, 09:43:38] [ 1204.466835@1] [ffffff80203dbe70+ 0][<ffffff800908c4d8>] do_notify_resume+0xf0/0x128 [01/06, 09:43:38] [ 1204.474636@1] [0000000000000000+ 0][<ffffff8009083d54>] work_pending+0x8/0x10 [01/06, 09:43:38] [ 1204.582815@1] hdmitx: hw: avmute set to 2 [01/06, 09:43:38] [ 1204.593483@1] meson_wdt ffd0f0d0.watchdog: reboot_notify: disable watchdog (event = 1) [01/06, 09:43:38] [ 1204.601784@2] meson-mmc: emmc: fixdiv calc done: adj = 22000 [01/06, 09:43:38] [ 1204.611743@2] av out status: low [01/06, 09:43:38] [ 1204.612028@2] aml_T9015_audio_remove! [01/06, 09:43:38] [ 1204.613453@2] amvecm: shutdown module [01/06, 09:43:38] [ 1204.620366@1] dim:dim_shutdown. [01/06, 09:43:38] [ 1204.621510@1] fb: osd_shutdown [01/06, 09:43:38] [ 1204.623201@1] amvdac_drv_shutdown: private_flag:0x0 [01/06, 09:43:38] [ 1204.628097@1] vout: vout2: aml_vout2_shutdown [01/06, 09:43:38] [ 1204.632084@1] vout: aml_vout_shutdown [01/06, 09:43:38] [ 1204.635712@1] VDIN(1) decode has stopped. [01/06, 09:43:38] [ 1204.639699@1] vdin_drv_shutdown ok. [01/06, 09:43:38] [ 1204.643159@1] VDIN(0) decode has stopped. [01/06, 09:43:38] [ 1204.647132@1] vdin_drv_shutdown ok. [01/06, 09:43:38] [ 1204.650670@1] aml_spdif_platform_shutdown is mute [01/06, 09:43:38] [ 1204.655261@1] aml_spdif_platform_shutdown is mute [01/06, 09:43:38] [ 1204.660046@1] gen_suspend [01/06, 09:43:38] [ 1204.679711@0] reboot: Restarting system with command 'private_recovery' [01/06, 09:43:38] [ 1204.680716@0] reboot reason 14 [01/06, 09:43:38] bl31 reboot reason: 0xd [01/06, 09:43:38] bl31 reboot reason: 0xe [01/06, 09:43:38] system cmd 1. [01/06, 09:43:38] G12A:BL:0253b8:61aa2d;FEAT:F2F939B2:32060;POC:F;RCY:0;EMMC:0;READ:0;0.0;0.0; [01/06, 09:43:38] bl2_stage_init 0x01 [01/06, 09:43:38] bl2_stage_init 0x81 [01/06, 09:43:38] hw id: 0x0000 - pwm id 0x01 [01/06, 09:43:38] bl2_stage_init 0xc1 [01/06, 09:43:38] bl2_stage_init 0x02 [01/06, 09:43:38] [01/06, 09:43:38] L0:0000037f [01/06, 09:43:38] L1:00000701 [01/06, 09:43:38] L2:00008067 [01/06, 09:43:38] L3:04000000 [01/06, 09:43:38] S1:00000000 [01/06, 09:43:38] B2:00032060 [01/06, 09:43:38] B1:f2f939b2 [01/06, 09:43:38] [01/06, 09:43:38] TE: 254617 [01/06, 09:43:38] [01/06, 09:43:38] BL2 Built : 06:41:45, Feb 19 2020. g12a g9a5414b - jenkins@walle02-sh [01/06, 09:43:38] [01/06, 09:43:38] Board ID = 1 [01/06, 09:43:38] Set cpu clk to 24M [01/06, 09:43:38] Set clk81 to 24M [01/06, 09:43:38] CPU clk: 1200 MHz [01/06, 09:43:38] Set clk81 to 166.6M [01/06, 09:43:38] eMMC boot @ 0 [01/06, 09:43:38] sw8 s [01/06, 09:43:38] OTP_ARB=00000001 [01/06, 09:43:38] board id: 1 [01/06, 09:43:38] Load FIP HDR DDR from eMMC, src: 0x00010200, des: 0xfffd0000, size: 0x00004000, part: 0 [01/06, 09:43:38] fw parse done [01/06, 09:43:38] PIEI prepare done [01/06, 09:43:38] 00000000 [01/06, 09:43:38] emmc switch 1 ok [01/06, 09:43:38] ddr saved addr:00016000 [01/06, 09:43:38] Load ddr parameter from eMMC, src: 0x02c00000, des: 0xfffd0000, size: 0x00001000, part: 0 [01/06, 09:43:38] 00000000 [01/06, 09:43:38] emmc switch 0 ok [01/06, 09:43:38] fastboot data verify [01/06, 09:43:38] result: 0 [01/06, 09:43:38] enable_fast_boot [01/06, 09:43:38] dmc_margin_rx==9 dmc_margin_tx==9 steps [01/06, 09:43:38] DDR4 probe [01/06, 09:43:39] [01/06, 09:43:39] LPDDR4_PHY_V_0_1_21-Built : 20:05:08, Jan 10 2020. g12a g3576a48 - zhiguang.ouyang@droid07-sz [01/06, 09:43:39] ddr clk to 1320MHz [01/06, 09:43:39] [01/06, 09:43:39] dmc_version 0000 [01/06, 09:43:39] Check phy result [01/06, 09:43:39] INFO : End of initialization [01/06, 09:43:39] INFO : Training has run successfully! [01/06, 09:43:39] dwc_ddrphy_apb_wr((0<<20)\|(2<<16)\|(0<<12)\|(0xb0):0001 [01/06, 09:43:39] dram_vref_reg_value 0x 00000018 [01/06, 09:43:39] DDR cs0 size: 2048MB [01/06, 09:43:39] DDR cs1 size: 1024MB [01/06, 09:43:39] DMC_DDR_CTRL: 0070001cDDR size: 3072MB [01/06, 09:43:39] cs0 DataBus test pass [01/06, 09:43:39] cs1 DataBus test pass [01/06, 09:43:39] cs0 AddrBus test pass [01/06, 09:43:39] cs1 AddrBus test pass [01/06, 09:43:39] [01/06, 09:43:39] non-sec scramble use zero key [01/06, 09:43:39] ddr scramble enabled [01/06, 09:43:39] [01/06, 09:43:39] 100bdlr_step_size ps== 485 [01/06, 09:43:39] result report [01/06, 09:43:39] boot times 3Enable ddr reg access [01/06, 09:43:39] 00000000 [01/06, 09:43:39] emmc switch 3 ok [01/06, 09:43:39] BL2: rpmb counter: 0x0000006e [01/06, 09:43:39] 00000000 [01/06, 09:43:39] emmc switch 0 ok [01/06, 09:43:39] Load FIP HDR from eMMC, src: 0x00010200, des: 0x01700000, size: 0x00004000, part: 0 [01/06, 09:43:39] Load BL3X from eMMC, src: 0x0006c200, des: 0x0175c000, size: 0x0013de00, part: 0 [01/06, 09:43:39] 0.0;0.0;M3 CHK:0;cm4_sp_mode 0 [01/06, 09:43:39] OPS=0x40 [01/06, 09:43:39] ring efuse init [01/06, 09:43:39] MVN_1=0x00000000 [01/06, 09:43:39] MVN_2=0x00000000 [01/06, 09:43:39] [Image: g12a_v1.1.3482-c90792be1 2020-06-12 19:52:03 wencai.you@droid11-sz] [01/06, 09:43:39] 28 0b 40 00 01 27 1d 00 00 0e 33 39 4d 50 4b 50 [01/06, 09:43:39] [1.166306 Inits done] [01/06, 09:43:39] secure task start! [01/06, 09:43:39] high task start! [01/06, 09:43:39] low task start! [01/06, 09:43:39] boot bl31 [01/06, 09:43:39] NOTICE: BL31: v1.3(release):25bab5d [01/06, 09:43:39] NOTICE: BL31: Built : 16:03:26, Feb 18 2020 [01/06, 09:43:39] NOTICE: BL31: G12A secure boot! [01/06, 09:43:39] NOTICE: BL31: BL33 decompress pass [01/06, 09:43:39] INFO: BL3-2: ATOS-V2.4.4-144-gab19f4557 #1 Wed Dec 9 15:21:03 2020 +0800 arm [01/06, 09:43:39] INFO: BL3-2: Chip: G12A Rev: B (28:B - 40:2) [01/06, 09:43:39] INFO: BL3-2: crypto engine DMA [01/06, 09:43:39] INFO: BL3-2: secure time TEE [01/06, 09:43:39] INFO: BL3-2: CONFIG_DEVICE_SECURE 0xb200000e [01/06, 09:43:39] protect cbus registers [01/06, 09:43:39] [01/06, 09:43:39] [01/06, 09:43:39] U-Boot 2015.01-g0fbe4d6ead (Dec 17 2020 - 22:07:31) [01/06, 09:43:39] [01/06, 09:43:39] DRAM: 3 GiB [01/06, 09:43:39] Relocation Offset is: b6d77000 [01/06, 09:43:39] mmu cfg end: 0xc0000000 [01/06, 09:43:39] mmu cfg end: 0xc0000000 [01/06, 09:43:39] spi_post_bind(spifc): req_seq = 0 [01/06, 09:43:39] register usb cfg[0][1] = 00000000b7ea2e10 [01/06, 09:43:39] NAND: get_sys_clk_rate_mtd() 292, clock setting 200! [01/06, 09:43:39] bus cycle0: 6,timing: 7 [01/06, 09:43:39] NAND device id: 0 ff ff ff ff ff [01/06, 09:43:39] No NAND device found!!! [01/06, 09:43:39] nand init failed: -6 [01/06, 09:43:39] get_sys_clk_rate_mtd() 292, clock setting 200! [01/06, 09:43:39] bus cycle0: 6,timing: 7 [01/06, 09:43:39] NAND device id: 0 ff ff ff ff ff [01/06, 09:43:39] No NAND device found!!! [01/06, 09:43:39] nand init failed: -6 [01/06, 09:43:39] MMC: aml_priv->desc_buf = 0x00000000b3d67a70 [01/06, 09:43:39] aml_priv->desc_buf = 0x00000000b3d69db0 [01/06, 09:43:39] SDIO Port B: 0, SDIO Port C: 1 [01/06, 09:43:39] co-phase 0x3, tx-dly 0, clock 400000 [01/06, 09:43:39] co-phase 0x3, tx-dly 0, clock 400000 [01/06, 09:43:39] co-phase 0x3, tx-dly 0, clock 400000 [01/06, 09:43:39] emmc/sd response timeout, cmd8, cmd->cmdarg=0x1aa, status=0x1ff2800 [01/06, 09:43:39] emmc/sd response timeout, cmd55, cmd->cmdarg=0x0, status=0x1ff2800 [01/06, 09:43:40] co-phase 0x3, tx-dly 0, clock 400000 [01/06, 09:43:40] co-phase 0x3, tx-dly 0, clock 40000000 [01/06, 09:43:40] [set_emmc_calc_fixed_adj][875]find fixed adj_delay=20 [01/06, 09:43:40] init_part() 297: PART_TYPE_AML [01/06, 09:43:40] [mmc_init] mmc init success [01/06, 09:43:40] Amlogic multi-dtb tool [01/06, 09:43:40] Single dtb detected [01/06, 09:43:40] start dts,buffer=00000000b3d6c620,dt_addr=00000000b3d6c620 [01/06, 09:43:40] get_partition_from_dts() 92: ret 0 [01/06, 09:43:40] Amlogic multi-dtb tool [01/06, 09:43:40] Single dtb detected [01/06, 09:43:40] parts: 17 [01/06, 09:43:40] 00: logo 0000000000800000 1 [01/06, 09:43:40] 01: recovery 0000000001800000 1 [01/06, 09:43:40] 02: misc 0000000000800000 1 [01/06, 09:43:40] 03: dtbo 0000000000800000 1 [01/06, 09:43:40] 04: cri_data 0000000000800000 2 [01/06, 09:43:40] 05: param 0000000001000000 2 [01/06, 09:43:40] 06: boot 0000000001000000 1 [01/06, 09:43:40] set has_boot_slot = 0 [01/06, 09:43:40] 07: rsv 0000000001000000 1 [01/06, 09:43:40] 08: metadata 0000000001000000 1 [01/06, 09:43:40] 09: vbmeta 0000000000200000 1 [01/06, 09:43:40] 10: tee 0000000002000000 1 [01/06, 09:43:40] 11: factory 0000000000800000 11 [01/06, 09:43:40] 12: super 0000000065200000 1 [01/06, 09:43:40] enable dynamic_partition [01/06, 09:43:40] 13: cache 0000000043800000 2 [01/06, 09:43:40] 14:factory_setting 0000000001000000 1 [01/06, 09:43:40] 15: btv_home 0000000006400000 1 [01/06, 09:43:40] 16: data ffffffffffffffff 4 [01/06, 09:43:40] init_part() 297: PART_TYPE_AML [01/06, 09:43:40] eMMC/TSD partition table have been checked OK! [01/06, 09:43:40] crc32_s:0x1577dad == storage crc_pattern:0x1577dad!!! [01/06, 09:43:40] crc32_s:0xee152b83 == storage crc_pattern:0xee152b83!!! [01/06, 09:43:40] crc32_s:0x7fd3b243 == storage crc_pattern:0x7fd3b243!!! [01/06, 09:43:40] mmc env offset: 0x4ac00000 [01/06, 09:43:40] In: serial [01/06, 09:43:40] Out: serial [01/06, 09:43:40] Err: serial [01/06, 09:43:40] reboot_mode=recovery_quiescent [01/06, 09:43:40] [store]To run cmd[emmc dtb_read 0x1000000 0x40000] [01/06, 09:43:40] _verify_dtb_checksum()-3354: calc df3edcc2, store df3edcc2 [01/06, 09:43:40] _verify_dtb_checksum()-3354: calc df3edcc2, store df3edcc2 [01/06, 09:43:40] dtb_read()-3569: total valid 2 [01/06, 09:43:40] update_old_dtb()-3550: do nothing [01/06, 09:43:40] Amlogic multi-dtb tool [01/06, 09:43:40] Single dtb detected [01/06, 09:43:40] amlkey_init() enter! [01/06, 09:43:40] [EFUSE_MSG]keynum is 4 [01/06, 09:43:40] vpu: driver version: v20190313 [01/06, 09:43:40] vpu: detect chip type: 8 [01/06, 09:43:40] vpu: clk_level default: 7(666667000Hz), max: 7(666667000Hz) [01/06, 09:43:40] vpu: clk_level in dts: 7 [01/06, 09:43:40] vpu: vpu_power_on [01/06, 09:43:40] vpu: set_vpu_clk [01/06, 09:43:40] vpu: set clk: 666667000Hz, readback: 666666667Hz(0x100) [01/06, 09:43:40] vpu: set_vpu_clk finish [01/06, 09:43:40] vpu: vpu_module_init_config [01/06, 09:43:40] vpp: vpp_init [01/06, 09:43:40] vpp: vpp osd2 matrix rgb2yuv.............. [01/06, 09:43:40] hdr_func 4, hdr_process_select 0x1 [01/06, 09:43:40] hdr_func 2, hdr_process_select 0x1 [01/06, 09:43:40] cvbs: cpuid:0x28 [01/06, 09:43:40] cvbs: find clk_path: 0x0 [01/06, 09:43:40] cvbs: find performance_pal config [01/06, 09:43:40] cvbs: find performance_ntsc config [01/06, 09:43:40] aml_config_dtb 633 [01/06, 09:43:40] aml_config_dtb 663 [01/06, 09:43:40] libfdt fdt_path_offset() returned FDT_ERR_NOTFOUND [01/06, 09:43:40] co_phase = <0x00000003> [01/06, 09:43:40] caps2 = "MMC_CAP2_HS200" [01/06, 09:43:40] f_max = " [01/06, 09:43:40] 倫" [01/06, 09:43:40] status = "disabled" [01/06, 09:43:40] libfdt fdt_path_offset() returned FDT_ERR_NOTFOUND [01/06, 09:43:40] Net: dwmac.ff3f0000amlkey_init() enter! [01/06, 09:43:40] amlkey_init() 71: already init! [01/06, 09:43:40] [EFUSE_MSG]keynum is 4 [01/06, 09:43:40] [01/06, 09:43:40] ramdump_init, add:0, size:0 [01/06, 09:43:40] CONFIG_AVB2: avb2 [01/06, 09:43:40] Start read misc partition datas! [01/06, 09:43:40] info->magic = [01/06, 09:43:40] info->version_major = 1 [01/06, 09:43:40] info->version_minor = 0 [01/06, 09:43:40] info->slots[0].priority = 15 [01/06, 09:43:40] info->slots[0].tries_remaining = 7 [01/06, 09:43:40] info->slots[0].successful_boot = 0 [01/06, 09:43:40] info->slots[1].priority = 14 [01/06, 09:43:40] info->slots[1].tries_remaining = 7 [01/06, 09:43:40] info->slots[1].successful_boot = 0 [01/06, 09:43:40] info->crc32 = -1075449479 [01/06, 09:43:40] active slot = 0 [01/06, 09:43:40] wipe_data=successful [01/06, 09:43:40] wipe_cache=successful [01/06, 09:43:40] upgrade_step=2 [01/06, 09:43:40] gpio: pin GPIOX_7 (gpio 87) value is 1 [01/06, 09:43:40] gpio: pin GPIOAO_10 (gpio 10) value is 1 [01/06, 09:43:40] gpio: pin GPIOX_5 (gpio 85) value is 1 [01/06, 09:43:40] reboot_mode:::: recovery_quiescent [01/06, 09:43:40] gpio: pin GPIOX_7 (gpio 87) value is 1 [01/06, 09:43:40] gpio: pin GPIOAO_10 (gpio 10) value is 0 [01/06, 09:43:40] gpio: pin GPIOX_5 (gpio 85) value is 0 [01/06, 09:43:40] s_version: U-Boot 2015.01-g0fbe4d6ead [01/06, 09:43:40] amlkey_init() enter! [01/06, 09:43:40] amlkey_init() 71: already init! [01/06, 09:43:40] [EFUSE_MSG]keynum is 4 [01/06, 09:43:40] [KM]Error:f[key_manage_query_size]L515:key[oemkey] not programed yet [01/06, 09:43:40] Interface: MMC [01/06, 09:43:40] Device 1: Vendor: Man 000015 Snr 913643e2 Rev: 0.6 Prod: 8GTF4R [01/06, 09:43:40] Type: Removable Hard Disk [01/06, 09:43:40] Capacity: 7456.0 MB = 7.2 GB (15269888 x 512) [01/06, 09:43:40] Filesystem: FAT12 "KEYBOX PART" [01/06, 09:43:40] turn off hdmitx [01/06, 09:43:40] s_version: U-Boot 2015.01-g0fbe4d6ead [01/06, 09:43:40] amlkey_init() enter! [01/06, 09:43:40] amlkey_init() 71: already init! [01/06, 09:43:40] [EFUSE_MSG]keynum is 4 [01/06, 09:43:40] [KM]Error:f[key_manage_query_size]L515:key[oemkey] not programed yet [01/06, 09:43:40] Interface: MMC [01/06, 09:43:40] Device 1: Vendor: Man 000015 Snr 913643e2 Rev: 0.6 Prod: 8GTF4R [01/06, 09:43:40] Type: Removable Hard Disk [01/06, 09:43:40] Capacity: 7456.0 MB = 7.2 GB (15269888 x 512) [01/06, 09:43:40] Filesystem: FAT12 "KEYBOX PART" [01/06, 09:43:40] gpio: pin GPIOAO_3 (gpio 3) value is 1 [01/06, 09:43:40] Command: bcb uboot-command [01/06, 09:43:40] Start read misc partition datas! [01/06, 09:43:40] BCB hasn't any datas,exit! [01/06, 09:43:40] gpio: pin GPIOX_7 (gpio 87) value is 1 [01/06, 09:43:40] gpio: pin GPIOAO_10 (gpio 10) value is 1 [01/06, 09:43:40] gpio: pin GPIOX_5 (gpio 85) value is 1 [01/06, 09:43:40] gpio: pin GPIOX_7 (gpio 87) value is 1 [01/06, 09:43:40] gpio: pin GPIOAO_10 (gpio 10) value is 0 [01/06, 09:43:40] gpio: pin GPIOX_5 (gpio 85) value is 0 [01/06, 09:43:40] s_version: U-Boot 2015.01-g0fbe4d6ead [01/06, 09:43:40] amlkey_init() enter! [01/06, 09:43:40] amlkey_init() 71: already init! [01/06, 09:43:40] [EFUSE_MSG]keynum is 4 [01/06, 09:43:40] [KM]Error:f[key_manage_query_size]L515:key[oemkey] not programed yet [01/06, 09:43:40] Interface: MMC [01/06, 09:43:40] Device 1: Vendor: Man 000015 Snr 913643e2 Rev: 0.6 Prod: 8GTF4R [01/06, 09:43:40] Type: Removable Hard Disk [01/06, 09:43:40] Capacity: 7456.0 MB = 7.2 GB (15269888 x 512) [01/06, 09:43:40] Filesystem: FAT12 "KEYBOX PART" [01/06, 09:43:40] InUsbBurn [01/06, 09:43:41] wait for phy ready count is 0 [01/06, 09:43:41] noSof [01/06, 09:43:41] sof timeout, reset usb phy tuning [01/06, 09:43:41] card out [01/06, 09:43:41] [MSG]mmcinfo failed! [01/06, 09:43:41] card out [01/06, 09:43:41] (Re)start USB... [01/06, 09:43:41] USB0: USB3.0 XHCI init start [01/06, 09:43:41] Register 3000140 NbrPorts 2 [01/06, 09:43:41] Starting the controller [01/06, 09:43:41] USB XHCI 1.10 [01/06, 09:43:42] scanning bus 0 for devices... 1 USB Device(s) found [01/06, 09:43:42] scanning usb for storage devices... 0 Storage Device(s) found [01/06, 09:43:42] Bad device usb 0 [01/06, 09:43:42] Bad device usb 0 [01/06, 09:43:42] Start read misc partition datas! [01/06, 09:43:42] info->magic = [01/06, 09:43:42] info->version_major = 1 [01/06, 09:43:43] info->version_minor = 0 [01/06, 09:43:43] info->slots[0].priority = 15 [01/06, 09:43:43] info->slots[0].tries_remaining = 7 [01/06, 09:43:43] info->slots[0].successful_boot = 0 [01/06, 09:43:43] info->slots[1].priority = 14 [01/06, 09:43:43] info->slots[1].tries_remaining = 7 [01/06, 09:43:43] info->slots[1].successful_boot = 0 [01/06, 09:43:43] info->crc32 = -1075449479 [01/06, 09:43:43] active slot = 0 [01/06, 09:43:43] active_slot: normal [01/06, 09:43:43] Unknown command 'wipeisb' - try 'help' [01/06, 09:43:43] avb2: 1 [01/06, 09:43:43] Start read misc partition datas! [01/06, 09:43:43] info->magic = [01/06, 09:43:43] info->version_major = 1 [01/06, 09:43:43] info->version_minor = 0 [01/06, 09:43:43] info->slots[0].priority = 15 [01/06, 09:43:43] info->slots[0].tries_remaining = 7 [01/06, 09:43:43] info->slots[0].successful_boot = 0 [01/06, 09:43:43] info->slots[1].priority = 14 [01/06, 09:43:43] info->slots[1].tries_remaining = 7 [01/06, 09:43:43] info->slots[1].successful_boot = 0 [01/06, 09:43:43] info->crc32 = -1075449479 [01/06, 09:43:43] active slot = 0 [01/06, 09:43:43] active_slot is normal [01/06, 09:43:43] ab_suffix is [01/06, 09:43:43] avb_slot_verify.c:525: DEBUG: Loading vbmeta struct from partition 'vbmeta'. [01/06, 09:43:43] AVB2 verify with default kpub [01/06, 09:43:43] avb_slot_verify.c:286: DEBUG: boot: Loading entire partition. [01/06, 09:43:44] avb_slot_verify.c:286: DEBUG: dtb: Loading entire partition. [01/06, 09:43:44] [store]To run cmd[emmc dtb_read 0x00000000b4dec8f0 0x40000] [01/06, 09:43:44] dtb_read_shortcut()-3412: short cut in... [01/06, 09:43:44] avb_slot_verify.c:286: DEBUG: recovery: Loading entire partition. [01/06, 09:43:46] Start read misc partition datas! [01/06, 09:43:46] info->magic = [01/06, 09:43:46] info->version_major = 1 [01/06, 09:43:46] info->version_minor = 0 [01/06, 09:43:46] info->slots[0].priority = 15 [01/06, 09:43:46] info->slots[0].tries_remaining = 7 [01/06, 09:43:46] info->slots[0].successful_boot = 0 [01/06, 09:43:46] info->slots[1].priority = 14 [01/06, 09:43:46] info->slots[1].tries_remaining = 7 [01/06, 09:43:46] info->slots[1].successful_boot = 0 [01/06, 09:43:46] info->crc32 = -1075449479 [01/06, 09:43:46] active slot = 0 [01/06, 09:43:46] active_slot is normal [01/06, 09:43:46] Start read misc partition datas! [01/06, 09:43:46] info->magic = [01/06, 09:43:46] info->version_major = 1 [01/06, 09:43:46] info->version_minor = 0 [01/06, 09:43:46] info->slots[0].priority = 15 [01/06, 09:43:46] info->slots[0].tries_remaining = 7 [01/06, 09:43:46] info->slots[0].successful_boot = 0 [01/06, 09:43:46] info->slots[1].priority = 14 [01/06, 09:43:46] info->slots[1].tries_remaining = 7 [01/06, 09:43:46] info->slots[1].successful_boot = 0 [01/06, 09:43:46] info->crc32 = -1075449479 [01/06, 09:43:46] active slot = 0 [01/06, 09:43:46] active_slot is normal [01/06, 09:43:46] Start read misc partition datas! [01/06, 09:43:46] info->magic = [01/06, 09:43:46] info->version_major = 1 [01/06, 09:43:46] info->version_minor = 0 [01/06, 09:43:46] info->slots[0].priority = 15 [01/06, 09:43:46] info->slots[0].tries_remaining = 7 [01/06, 09:43:46] info->slots[0].successful_boot = 0 [01/06, 09:43:46] info->slots[1].priority = 14 [01/06, 09:43:46] info->slots[1].tries_remaining = 7 [01/06, 09:43:46] info->slots[1].successful_boot = 0 [01/06, 09:43:46] info->crc32 = -1075449479 [01/06, 09:43:46] active slot = 0 [01/06, 09:43:46] active_slot is normal [01/06, 09:43:46] avb verification: locked = 0, result = 0 [01/06, 09:43:46] ee_gate_off ... [01/06, 09:43:46] avb2: 1 [01/06, 09:43:46] ## Booting Android Image at 0x01080000 ... [01/06, 09:43:46] reloc_addr =b3dec8e0 [01/06, 09:43:46] copy done [01/06, 09:43:46] Kernel command line: androidboot.dynamic_partitions=true androidboot.dtbo_idx=0 androidboot.boot_devices=ffe07000.emmc buildvariant=userdebug [01/06, 09:43:46] [store]Is good fdt check header, no need decrypt! [01/06, 09:43:46] active_slot is normal [01/06, 09:43:46] load dtb from 0x1000000 ...... [01/06, 09:43:46] Amlogic multi-dtb tool [01/06, 09:43:46] Single dtb detected [01/06, 09:43:46] find 1 dtbos [01/06, 09:43:46] dtbos to be applied: 0 [01/06, 09:43:46] Apply dtbo 0 [01/06, 09:43:46] Uncompressing Kernel Image ... OK [01/06, 09:43:46] kernel loaded at 0x01080000, end = 0x02bc1200 [01/06, 09:43:46] libfdt fdt_getprop(): FDT_ERR_NOTFOUND [01/06, 09:43:47] Loading Ramdisk to b30ab000, end b3d6420e ... OK [01/06, 09:43:47] Loading Device Tree to 000000001ffe9000, end 000000001ffffac3 ... OK [01/06, 09:43:47] fdt_fixup_memory_banks, reg:0000000000000000 [01/06, 09:43:47] [01/06, 09:43:47] Starting kernel ... [01/06, 09:43:47] [01/06, 09:43:47] uboot time: 8481977 us [01/06, 09:43:49] [ 0.000000@0] Booting Linux on physical CPU 0x0 [01/06, 09:43:49] [ 0.000000@0] Linux version 4.9.180-ga8acf1570ede-dirty (root@BuildHost) (gcc version 6.3.1 20170109 (Linaro GCC 6.3-2017.02) ) #57 SMP PREEMPT Thu Dec 17 21:47:21 CST 2020 [01/06, 09:43:49] [ 0.000000@0] Boot CPU: AArch64 Processor [410fd034] [01/06, 09:43:49] [ 0.000000@0] efi: Getting EFI parameters from FDT: [01/06, 09:43:49] [ 0.000000@0] efi: UEFI not found. [01/06, 09:43:49] [ 0.000000@0] 07400000 - 07500000, 1024 KB, ramoops@0x07400000 [01/06, 09:43:49] [ 0.000000@0] __reserved_mem_alloc_size, start:0x0000000005000000, end:0x0000000007400000, len:36 MiB [01/06, 09:43:49] [ 0.000000@0] 05000000 - 07400000, 36864 KB, linux,secmon [01/06, 09:43:49] [ 0.000000@0] __reserved_mem_alloc_size, start:0x00000000bf800000, end:0x00000000c0000000, len:8 MiB [01/06, 09:43:49] [ 0.000000@0] bf800000 - c0000000, 8192 KB, linux,meson-fb [01/06, 09:43:49] [ 0.000000@0] __reserved_mem_alloc_size, start:0x0000000078400000, end:0x0000000080400000, len:128 MiB [01/06, 09:43:49] [ 0.000000@0] 78400000 - 80400000, 131072 KB, linux,ion-dev [01/06, 09:43:49] [ 0.000000@0] __reserved_mem_alloc_size, start:0x000000006c400000, end:0x0000000078400000, len:192 MiB [01/06, 09:43:49] [ 0.000000@0] 6c400000 - 78400000, 196608 KB, linux,di_cma [01/06, 09:43:49] [ 0.000000@0] Reserved memory: created DMA memory pool at 0x00000000bf800000, size 0 MiB [01/06, 09:43:49] [ 0.000000@0] bf800000 - bf800000, 0 KB, linux,ppmgr [01/06, 09:43:49] [ 0.000000@0] __reserved_mem_alloc_size, start:0x0000000056000000, end:0x000000006c400000, len:356 MiB [01/06, 09:43:49] [ 0.000000@0] 56000000 - 6c400000, 364544 KB, linux,codec_mm_cma [01/06, 09:43:49] [ 0.000000@0] bf800000 - bf800000, 0 KB, linux,codec_mm_reserved [01/06, 09:43:49] [ 0.000000@0] bb800000 - bf800000, 65536 KB, linux,vdin0_cma [01/06, 09:43:49] [ 0.000000@0] b7800000 - bb800000, 65536 KB, linux,vdin1_cma [01/06, 09:43:49] [ 0.000000@0] cma: Reserved 8 MiB at 0x00000000b7000000 [01/06, 09:43:49] [ 0.000000@0] On node 0 totalpages: 786176 [01/06, 09:43:49] [ 0.000000@0] DMA zone: 12284 pages used for memmap [01/06, 09:43:49] [ 0.000000@0] DMA zone: 0 pages reserved [01/06, 09:43:49] [ 0.000000@0] DMA zone: 786176 pages, LIFO batch:31 [01/06, 09:43:49] [ 0.000000@0] psci: probing for conduit method from DT. [01/06, 09:43:49] [ 0.000000@0] psci: PSCIv1.0 detected in firmware. [01/06, 09:43:49] [ 0.000000@0] psci: Using standard PSCI v0.2 function IDs [01/06, 09:43:49] [ 0.000000@0] psci: Trusted OS migration not required [01/06, 09:43:49] [ 0.000000@0] psci: SMC Calling Convention v1.1 [01/06, 09:43:49] [ 0.000000@0] percpu: Embedded 26 pages/cpu s69336 r8192 d28968 u106496 [01/06, 09:43:49] [ 0.000000@0] pcpu-alloc: s69336 r8192 d28968 u106496 alloc=264096 [01/06, 09:43:49] [ 0.000000@0] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [01/06, 09:43:49] [ 0.000000@0] Detected VIPT I-cache on CPU0 [01/06, 09:43:49] [ 0.000000@0] CPU features: enabling workaround for ARM erratum 845719 [01/06, 09:43:49] [ 0.000000@0] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 773892 [01/06, 09:43:49] [ 0.000000@0] Kernel command line: init=/init console=ttyS0,115200 no_console_suspend earlyprintk=aml-uart,0xff803000 ramoops.pstore_en=1 ramoops.record_size=0x8000 ramoops.console_size=0x4000 loglevel=9 hdr_priority= otg_device=1 reboot_mode_android=normal logo=osd0,loaded,0x3d800000 fb_width=1920 fb_height=1080 display_bpp=16 outputmode=1080p60hz vout=1080p60hz,enable panel_type=lcd_1 lcd_ctrl=0x00000000 hdmitx=,444,8bit hdmimode=1080p60hz hdmichecksum=0x69b10000 dolby_vision_on=0 frac_rate_policy=1 hdmi_read_edid=1 cvbsmode=576cvbs osd_reverse=0 video_reverse=0 irq_check_en=0 ledsTrigger=0 androidboot.enableSystemReset=0 androidboot.enablePrivateRecovery=1 androidboot.selinux=enforcing androidboot.firstboot=0 jtag=disable androidboot.hardware=amlogic androidboot.bootloader=U-Boot 2015.01-g0fbe4d6ead androidboot.build.expect.baseband=N/A androidboot.serialno=4669688001201904000275 mac=EC:5C:68:1D:BC:CC androidboot.mac=EC:5C:68:1D:BC:CC mac_bt=EC:5C:68:1D:BE:5E androidboot.mac_bt=EC:5C:6 [01/06, 09:43:49] [ 0.000000@0] fb: osd0 [01/06, 09:43:49] [ 0.000000@0] fb: loaded [01/06, 09:43:49] [ 0.000000@0] fb: 0x3d800000 [01/06, 09:43:49] [ 0.000000@0] fb: logo_info.fb_width=1920 [01/06, 09:43:49] [ 0.000000@0] fb: logo_info.fb_height=1080 [01/06, 09:43:49] [ 0.000000@0] vout: 1080p60hz [01/06, 09:43:49] [ 0.000000@0] vout: enable: 1 [01/06, 09:43:49] [ 0.000000@0] lcd: panel_type: lcd_1 [01/06, 09:43:49] [ 0.000000@0] lcd: lcd_ctrl: 0x00000000 [01/06, 09:43:49] [ 0.000000@0] get_dolby_on: 0 [01/06, 09:43:49] [ 0.000000@0] hdmitx: hdmitx boot frac_rate_policy: 1 [01/06, 09:43:49] [ 0.000000@0] vpp_axis_reverse: bootargs is 0 [01/06, 09:43:49] [ 0.000000@0] DI: di_read_canvas_reverse: bootargs is 0. [01/06, 09:43:49] [ 0.000000@0] vdin_get_video_reverse: bootargs is 0. [01/06, 09:43:49] [ 0.000000@0] phlock_phase_config: bootargs is 0. [01/06, 09:43:49] [ 0.000000@0] uboot leds trigger: 0 [01/06, 09:43:49] [ 0.000000@0] try to read bt mac from emmc key! [01/06, 09:43:49] [ 0.000000@0] PID hash table entries: 4096 (order: 3, 32768 bytes) [01/06, 09:43:49] [ 0.000000@0] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes) [01/06, 09:43:49] [ 0.000000@0] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes) [01/06, 09:43:49] [ 0.000000@0] Memory: 2168312K/3144704K available (14652K kernel code, 1988K rwdata, 5776K rodata, 5440K init, 1555K bss, 99848K reserved, 876544K cma-reserved) [01/06, 09:43:49] [ 0.000000@0] Virtual kernel memory layout: [01/06, 09:43:49] [ 0.000000@0] modules : 0xffffff8000000000 - 0xffffff8008000000 ( 128 MB) [01/06, 09:43:49] [ 0.000000@0] vmalloc : 0xffffff8008000000 - 0xffffffbebfff0000 ( 250 GB) [01/06, 09:43:49] [ 0.000000@0] .text : 0xffffff8009080000 - 0xffffff8009ed0000 ( 14656 KB) [01/06, 09:43:49] [ 0.000000@0] .rodata : 0xffffff8009ed0000 - 0xffffff800a480000 ( 5824 KB) [01/06, 09:43:49] [ 0.000000@0] .init : 0xffffff800a480000 - 0xffffff800a9d0000 ( 5440 KB) [01/06, 09:43:49] [ 0.000000@0] .data : 0xffffff800a9d0000 - 0xffffff800abc1200 ( 1989 KB) [01/06, 09:43:49] [ 0.000000@0] .bss : 0xffffff800abc1200 - 0xffffff800ad4602c ( 1556 KB) [01/06, 09:43:49] [ 0.000000@0] fixed : 0xffffffbefe7fd000 - 0xffffffbefec00000 ( 4108 KB) [01/06, 09:43:49] [ 0.000000@0] PCI I/O : 0xffffffbefee00000 - 0xffffffbeffe00000 ( 16 MB) [01/06, 09:43:49] [ 0.000000@0] vmemmap : 0xffffffbf00000000 - 0xffffffc000000000 ( 4 GB maximum) [01/06, 09:43:49] [ 0.000000@0] 0xffffffbf00004000 - 0xffffffbf03000000 ( 47 MB actual) [01/06, 09:43:49] [ 0.000000@0] memory : 0xffffffc000100000 - 0xffffffc0c0000000 ( 3071 MB) [01/06, 09:43:49] [ 0.000000@0] can't find symbol:arm_dma_alloc [01/06, 09:43:49] [ 0.000000@0] can't find symbol:__alloc_from_contiguous [01/06, 09:43:49] [ 0.000000@0] can't find symbol:cma_allocator_alloc [01/06, 09:43:49] [ 0.000000@0] 0, addr:ffffff8009b5e258 + 140, aml_cma_alloc_post_hook [01/06, 09:43:49] [ 0.000000@0] 1, addr:ffffff8009574620 + 78, dma_alloc_from_contiguous [01/06, 09:43:49] [ 0.000000@0] 2, addr:ffffff8009231758 + 388, cma_alloc [01/06, 09:43:49] [ 0.000000@0] 3, addr:ffffff8009221138 + 310, __kmalloc_track_caller [01/06, 09:43:49] [ 0.000000@0] 4, addr:ffffff800921e1a0 + 310, __kmalloc [01/06, 09:43:49] [ 0.000000@0] 5, addr:ffffff800921de38 + 2a0, kmem_cache_alloc_trace [01/06, 09:43:49] [ 0.000000@0] 6, addr:ffffff800921dba0 + 298, kmem_cache_alloc [01/06, 09:43:49] [ 0.000000@0] 7, addr:ffffff800921db18 + 88, __slab_alloc.isra.23.constprop.27 [01/06, 09:43:49] [ 0.000000@0] 8, addr:ffffff800921d790 + 388, ___slab_alloc.constprop.28 [01/06, 09:43:49] [ 0.000000@0] 9, addr:ffffff800921b278 + 5d8, new_slab [01/06, 09:43:49] [ 0.000000@0] 10, addr:ffffff800920e6f8 + 70, vmalloc [01/06, 09:43:49] [ 0.000000@0] 11, addr:ffffff800920e610 + 70, vzalloc [01/06, 09:43:49] [ 0.000000@0] 12, addr:ffffff800920e0b8 + 2a8, __vmalloc_node_range [01/06, 09:43:49] [ 0.000000@0] 13, addr:ffffff80091f23a8 + 110, kmalloc_order_trace [01/06, 09:43:49] [ 0.000000@0] 14, addr:ffffff80091f2238 + 170, kmalloc_order [01/06, 09:43:49] [ 0.000000@0] 15, addr:ffffff80091d24b8 + 170, __alloc_page_frag [01/06, 09:43:49] [ 0.000000@0] 16, addr:ffffff80091d2450 + 68, alloc_pages_exact [01/06, 09:43:49] [ 0.000000@0] 17, addr:ffffff80091d2420 + 30, get_zeroed_page [01/06, 09:43:49] [ 0.000000@0] 18, addr:ffffff80091d23c8 + 58, __get_free_pages [01/06, 09:43:49] [ 0.000000@0] 19, addr:ffffff80091d15b0 + e18, __alloc_pages_nodemask [01/06, 09:43:49] [ 0.000000@0] 20, addr:ffffff80090a06d8 + 250, __dma_alloc [01/06, 09:43:49] [ 0.000000@0] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [01/06, 09:43:49] [ 0.000000@0] Preemptible hierarchical RCU implementation. [01/06, 09:43:49] [ 0.000000@0] Build-time adjustment of leaf fanout to 64. [01/06, 09:43:49] [ 0.000000@0] RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. [01/06, 09:43:49] [ 0.000000@0] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=4 [01/06, 09:43:49] [ 0.000000@0] NR_IRQS:64 nr_irqs:64 0 [01/06, 09:43:49] [ 0.000000@0] irq_meson_gpio: 100 to 8 gpio interrupt mux initialized [01/06, 09:43:49] [ 0.000000@0] g12a_aoclkc_init: register ao clk ok! [01/06, 09:43:49] [ 0.000000@0] Meson chip version = RevB (28:B - 40:0) [01/06, 09:43:49] [ 0.000000@0] meson_g12a_sdemmc_init: register amlogic sdemmc clk [01/06, 09:43:49] [ 0.000000@0] meson_g12a_sdemmc_init: register amlogic sdemmc clk [01/06, 09:43:49] [ 0.000000@0] meson_g12a_gpu_init: register meson gpu clk [01/06, 09:43:49] [ 0.000000@0] meson_g12a_media_init: register meson media clk [01/06, 09:43:49] [ 0.000000@0] meson_g12a_misc_init: register amlogic g12a misc clks [01/06, 09:43:49] [ 0.000000@0] meson_g12a_misc_init: done. [01/06, 09:43:49] [ 0.000000@0] g12a_clkc_init initialization complete [01/06, 09:43:49] [ 0.000000@0] arm_arch_timer: Architected cp15 timer(s) running at 24.00MHz (phys). [01/06, 09:43:49] [ 0.000000@0] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns [01/06, 09:43:49] [ 0.000004@0] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns [01/06, 09:43:49] [ 0.000034@0] meson_bc_timer: mclk->mux_reg = (ptrval),mclk->reg = (ptrval) [01/06, 09:43:49] [ 0.000525@0] Console: colour dummy device 80x25 [01/06, 09:43:49] [ 0.000540@0] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) [01/06, 09:43:49] [ 0.000550@0] pid_max: default: 32768 minimum: 301 [01/06, 09:43:49] [ 0.000607@0] thread_stack_cache_init, vmap: (ptrval), bitmap: (ptrval), cache page:1a0 [01/06, 09:43:49] [ 0.000615@0] thread_stack_cache_init, allocation vm area: (ptrval), addr: (ptrval), size:20001000 [01/06, 09:43:49] [ 0.000622@0] cpu 0, vmap_stack:[ffffffc0b6f2bba0-ffffffc0b6f2fb90] [01/06, 09:43:49] [ 0.000628@0] cpu 0, irq_stack: [ffffffc0b6f21060-ffffffc0b6f25050] [01/06, 09:43:49] [ 0.000633@0] cpu 1, vmap_stack:[ffffffc0b6f45ba0-ffffffc0b6f49b90] [01/06, 09:43:49] [ 0.000638@0] cpu 1, irq_stack: [ffffffc0b6f3b060-ffffffc0b6f3f050] [01/06, 09:43:49] [ 0.000644@0] cpu 2, vmap_stack:[ffffffc0b6f5fba0-ffffffc0b6f63b90] [01/06, 09:43:49] [ 0.000649@0] cpu 2, irq_stack: [ffffffc0b6f55060-ffffffc0b6f59050] [01/06, 09:43:49] [ 0.000655@0] cpu 3, vmap_stack:[ffffffc0b6f79ba0-ffffffc0b6f7db90] [01/06, 09:43:49] [ 0.000660@0] cpu 3, irq_stack: [ffffffc0b6f6f060-ffffffc0b6f73050] [01/06, 09:43:49] [ 0.000721@0] Security Framework initialized [01/06, 09:43:49] [ 0.000729@0] SELinux: Initializing. [01/06, 09:43:49] [ 0.000765@0] SELinux: Starting in permissive mode [01/06, 09:43:49] [ 0.000800@0] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes) [01/06, 09:43:49] [ 0.000811@0] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes) [01/06, 09:43:49] [ 0.001449@0] ftrace: allocating 46167 entries in 181 pages [01/06, 09:43:49] [ 0.091583@0] sched-energy: CPU device node has no sched-energy-costs [01/06, 09:43:49] [ 0.091600@0] CPU0: update cpu_capacity 1024 [01/06, 09:43:49] [ 0.103233@0] ASID allocator initialised with 65536 entries [01/06, 09:43:49] [ 0.143498@0] secmon: can't fine clear_range [01/06, 09:43:49] [ 0.144237@0] EFI services will not be available. [01/06, 09:43:49] [ 0.191796@1] Detected VIPT I-cache on CPU1 [01/06, 09:43:49] [ 0.191843@1] CPU1: update cpu_capacity 1024 [01/06, 09:43:49] [ 0.191846@1] CPU1: Booted secondary processor [410fd034] [01/06, 09:43:49] [ 0.223851@2] Detected VIPT I-cache on CPU2 [01/06, 09:43:49] [ 0.223876@2] CPU2: update cpu_capacity 1024 [01/06, 09:43:49] [ 0.223879@2] CPU2: Booted secondary processor [410fd034] [01/06, 09:43:49] [ 0.255941@3] Detected VIPT I-cache on CPU3 [01/06, 09:43:49] [ 0.255964@3] CPU3: update cpu_capacity 1024 [01/06, 09:43:49] [ 0.255967@3] CPU3: Booted secondary processor [410fd034] [01/06, 09:43:49] [ 0.256050@0] Brought up 4 CPUs [01/06, 09:43:49] [ 0.256086@0] SMP: Total of 4 processors activated. [01/06, 09:43:49] [ 0.256096@0] CPU features: detected feature: 32-bit EL0 Support [01/06, 09:43:49] [ 0.256193@0] CPU: All CPU(s) started at EL2 [01/06, 09:43:49] [ 0.256224@0] alternatives: patching kernel code [01/06, 09:43:49] [ 0.257098@1] addr: (ptrval) is in kernel, size fix 4096->10, data:mode=0755 [01/06, 09:43:49] [ 0.257269@0] devtmpfs: initialized [01/06, 09:43:49] [ 0.284475@0] DMI not present or invalid. [01/06, 09:43:49] [ 0.284772@0] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [01/06, 09:43:49] [ 0.284791@0] futex hash table entries: 1024 (order: 4, 65536 bytes) [01/06, 09:43:49] [ 0.284980@0] pinctrl core: initialized pinctrl subsystem [01/06, 09:43:49] [ 0.286033@0] NET: Registered protocol family 16 [01/06, 09:43:50] [ 0.290307@0] schedtune: init normalization constants... [01/06, 09:43:50] [ 0.290319@0] schedtune: no energy model data [01/06, 09:43:50] [ 0.290326@0] schedtune: disabled! [01/06, 09:43:50] [ 0.303971@0] cpuidle: using governor menu [01/06, 09:43:50] [ 0.304076@0] register canvas platform driver [01/06, 09:43:50] [ 0.304120@0] register rdma platform driver [01/06, 09:43:50] [ 0.308491@0] vdso: 2 pages (1 code @ (ptrval), 1 data @ (ptrval)) [01/06, 09:43:50] [ 0.308512@0] hw-breakpoint: found 6 breakpoint and 2 watchpoint registers. [01/06, 09:43:50] [ 0.308957@0] DMA: preallocated 256 KiB pool for atomic allocations [01/06, 09:43:50] [ 0.309295@0] clkmsr: clkmsr: driver init [01/06, 09:43:50] [ 0.309304@0] codec_mm_module_init [01/06, 09:43:50] [ 0.309348@0] media_configs_system_init [01/06, 09:43:50] [ 0.309665@0] aml_watch_point_probe, in, wp:2 [01/06, 09:43:50] [ 0.310177@0] pstore: using zlib compression [01/06, 09:43:50] [ 0.310975@0] console [pstore-1] enabled [01/06, 09:43:50] [ 0.311021@0] pstore: Registered ramoops as persistent store backend [01/06, 09:43:50] [ 0.311032@0] ramoops: attached 0x100000@0x7400000, ecc: 0/0 [01/06, 09:43:50] [ 0.311044@0] ramoops: ramoops_io_en:0 1 old:0x0 ftrace_size:0x40000 [01/06, 09:43:50] [ 0.313673@0] aml_iomap: amlogic iomap probe done [01/06, 09:43:50] [ 0.314324@0] vpu: driver version: v20190329(7-g12a) [01/06, 09:43:50] [ 0.314340@0] vpu: load vpu_clk: 666666667Hz(7) [01/06, 09:43:50] [ 0.314553@0] vpu: clktree_init [01/06, 09:43:50] [ 0.314619@0] vpu: vpu_probe OK [01/06, 09:43:50] [ 0.320633@0] clkmsr: msr_clk_reg0= (ptrval),msr_clk_reg2= (ptrval) [01/06, 09:43:50] [ 0.320656@0] clkmsr: msr_ring_reg0= (ptrval) [01/06, 09:43:50] [ 0.323736@0] audio_clocks: audio_clocks_probe done [01/06, 09:43:50] [ 0.328045@0] aml_vdac_config_probe: cpu_id:4, name:meson-g12ab-vdac [01/06, 09:43:50] [ 0.328322@0] aml_vdac_probe: ok [01/06, 09:43:50] [ 0.328538@0] canvas_probe reg= (ptrval),size=2000 [01/06, 09:43:50] [ 0.328566@0] canvas maped reg_base = (ptrval) [01/06, 09:43:50] [ 0.337448@0] rdma_probe,cpu_type:1, ver:0, len:8 [01/06, 09:43:50] [ 0.337664@0] rdma_register, rdma_table_addr (ptrval) rdma_table_addr_phy b7040000 reg_buf (ptrval) [01/06, 09:43:50] [ 0.337676@0] rdma_register success, handle 1 table_size 32768 [01/06, 09:43:50] [ 0.337687@0] set_rdma_handle video rdma handle = 1. [01/06, 09:43:50] [ 0.337723@0] classs created ok [01/06, 09:43:50] [ 0.337741@0] classs file created ok [01/06, 09:43:50] [ 0.338668@0] aml_snd_reg_map[0], reg:ff640000, size:2000 [01/06, 09:43:50] [ 0.338695@0] aml_snd_reg_map[1], reg:ff642000, size:2000 [01/06, 09:43:50] [ 0.338720@0] aml_snd_reg_map[2], reg:ff64a000, size:2000 [01/06, 09:43:50] [ 0.338744@0] aml_snd_reg_map[3], reg:ff642800, size:1800 [01/06, 09:43:50] [ 0.338771@0] aml_snd_reg_map[4], reg:ffd01000, size:1000 [01/06, 09:43:50] [ 0.338781@0] amlogic auge_snd_iomap probe done [01/06, 09:43:50] [ 0.341121@0] cvbs_out: cvbsout_probe, cpu_id:3,name:meson-g12a-cvbsout [01/06, 09:43:50] [ 0.341285@0] cvbs_out: clk path:0x0 [01/06, 09:43:50] [ 0.341303@0] cvbs_out: find performance_pal config [01/06, 09:43:50] [ 0.341331@0] cvbs_out: find performance_ntsc config [01/06, 09:43:50] [ 0.341356@0] vout: vout1: register server: cvbs_vout_server [01/06, 09:43:50] [ 0.341366@0] cvbs_out: register cvbs module server ok [01/06, 09:43:50] [ 0.341377@0] vout: vout2: register server: cvbs_vout2_server [01/06, 09:43:50] [ 0.341386@0] cvbs_out: register cvbs module vout2 server ok [01/06, 09:43:50] [ 0.341398@0] cvbs_out: chrdev devno 264241152 for disp [01/06, 09:43:50] [ 0.341617@0] cvbs_out: create cdev cvbs [01/06, 09:43:50] [ 0.341628@0] cvbs_out: cvbsout_probe OK [01/06, 09:43:50] [ 0.342392@0] codec_mm codec_mm: assigned reserved memory node linux,codec_mm_cma [01/06, 09:43:50] [ 0.342485@0] codec_mm codec_mm: assigned reserved memory node linux,codec_mm_cma [01/06, 09:43:50] [ 0.342496@0] codec_mm_probe ok [01/06, 09:43:50] [ 0.618355@0] vgaarb: loaded [01/06, 09:43:50] [ 0.618711@0] SCSI subsystem initialized [01/06, 09:43:50] [ 0.619008@0] usbcore: registered new interface driver usbfs [01/06, 09:43:50] [ 0.619078@0] usbcore: registered new interface driver hub [01/06, 09:43:50] [ 0.619162@0] usbcore: registered new device driver usb [01/06, 09:43:50] [ 0.619315@0] Linux video capture interface: v2.00 [01/06, 09:43:50] [ 0.619432@0] pps_core: LinuxPPS API ver. 1 registered [01/06, 09:43:50] [ 0.619443@0] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [01/06, 09:43:50] [ 0.619470@0] PTP clock support registered [01/06, 09:43:50] [ 0.621415@0] dmi: Firmware registration failed. [01/06, 09:43:50] [ 0.621774@0] secmon: reserve_mem_size:0x2300000 [01/06, 09:43:50] [ 0.621869@0] secmon secmon: assigned reserved memory node linux,secmon [01/06, 09:43:50] [ 0.622979@0] secmon: get page: (ptrval), 5000 [01/06, 09:43:50] [ 0.623002@0] secmon: share in base: 0xffffffc0050fe000, share out base: 0xffffffc0050ff000 [01/06, 09:43:50] [ 0.623012@0] secmon: phy_in_base: 0x50fe000, phy_out_base: 0x50ff000 [01/06, 09:43:50] [ 0.623427@0] fb: osd_init_module [01/06, 09:43:50] [ 0.623957@0] fb: viu vsync irq: 39 [01/06, 09:43:50] [ 0.623983@0] fb: viu2 vsync irq: 47 [01/06, 09:43:50] [ 0.624109@0] 0x000000d8:Y=d8,U=0,V=0 [01/06, 09:43:50] [ 0.624120@0] 0x000000d9:Y=d9,U=0,V=0 [01/06, 09:43:50] [ 0.624130@0] 0x000000da:Y=da,U=0,V=0 [01/06, 09:43:50] [ 0.624140@0] 0x000000db:Y=db,U=0,V=0 [01/06, 09:43:50] [ 0.624150@0] 0x000000dc:Y=dc,U=0,V=0 [01/06, 09:43:50] [ 0.624160@0] 0x000000dd:Y=dd,U=0,V=0 [01/06, 09:43:50] [ 0.624420@0] fb: osd_rdma_init: rdma_table p=0xb7048000,op=0xb7048000 , v=0x (ptrval) [01/06, 09:43:50] [ 0.624455@0] rdma_register, rdma_table_addr (ptrval) rdma_table_addr_phy b7049000 reg_buf (ptrval) [01/06, 09:43:50] [ 0.624467@0] rdma_register success, handle 2 table_size 4096 [01/06, 09:43:50] [ 0.624477@0] fb: osd_rdma_init:osd rdma handle = 2. [01/06, 09:43:50] [ 0.624496@0] fb: mem_size: 0x800000 [01/06, 09:43:50] [ 0.624506@0] fb: mem_size: 0x1980000 [01/06, 09:43:50] [ 0.624515@0] fb: mem_size: 0x100000 [01/06, 09:43:50] [ 0.624524@0] fb: mem_size: 0x100000 [01/06, 09:43:50] [ 0.624533@0] fb: mem_size: 0x800000 [01/06, 09:43:50] [ 0.624594@0] meson-fb meson-fb: assigned reserved memory node linux,meson-fb [01/06, 09:43:50] [ 0.624605@0] fb: reserved memory base:0x00000000bf800000, size:800000 [01/06, 09:43:50] [ 0.624835@0] vout: error: invalid vinfo1. current vmode is not supported [01/06, 09:43:50] [ 0.624850@0] fb: init fbdev bpp is:32 [01/06, 09:43:50] [ 0.626414@0] fb: set osd0 reverse as NONE [01/06, 09:43:50] [ 0.626437@0] vout: error: invalid vinfo1. current vmode is not supported [01/06, 09:43:50] [ 0.633260@0] fb: osd probe OK [01/06, 09:43:50] [ 0.634118@0] hdmitx: system: amhdmitx_probe start [01/06, 09:43:50] [ 0.634130@0] hdmitx: system: Ver: 20190815 [01/06, 09:43:50] [ 0.634166@0] hdmitx: system: hdmitx_device.chip_type : 10 [01/06, 09:43:50] [ 0.634177@0] hdmitx: system: hdmitx_device.dongle_mode: 0 [01/06, 09:43:50] [ 0.634217@0] hdmitx: system: not find match pwr-ctl [01/06, 09:43:50] [ 0.634265@0] hdmitx: system: not find drm_amhdmitx [01/06, 09:43:50] [ 0.634298@0] hdmitx: system: hpd irq = 33 [01/06, 09:43:50] [ 0.634416@0] hdmitx: system: hdcp22_tx_skp failed to probe [01/06, 09:43:50] [ 0.634429@0] hdmitx: system: hdcp22_tx_esm failed to probe [01/06, 09:43:50] [ 0.634995@0] hdmitx: hdmitx20: Mapped PHY: 0xffd00000 [01/06, 09:43:50] [ 0.635015@0] hdmitx: hdmitx20: Mapped PHY: 0xff634400 [01/06, 09:43:50] [ 0.635035@0] hdmitx: hdmitx20: Mapped PHY: 0xff900000 [01/06, 09:43:50] [ 0.635058@0] hdmitx: hdmitx20: Mapped PHY: 0xff800000 [01/06, 09:43:50] [ 0.635072@0] hdmitx: hdmitx20: Mapped PHY: 0xff63c000 [01/06, 09:43:50] [ 0.635084@0] hdmitx: hdmitx20: Mapped PHY: 0xffd00000 [01/06, 09:43:50] [ 0.635097@0] hdmitx: hdmitx20: Mapped PHY: 0xff608000 [01/06, 09:43:50] [ 0.635110@0] hdmitx: hdmitx20: Mapped PHY: 0xff600000 [01/06, 09:43:50] [ 0.635123@0] hdmitx: hdmitx20: Mapped PHY: 0xffe01000 [01/06, 09:43:50] [ 0.635139@0] hdmitx: hw: hdmitx_get_format:0x0 [01/06, 09:43:50] [ 0.635148@0] hdmitx: hw: P_HHI_HDMI_CLK_CNTL :0x100 [01/06, 09:43:50] [ 0.635158@0] hdmitx: hw: P_HHI_HDMI_PLL_CNTL :0x0 [01/06, 09:43:50] [ 0.635393@0] hdmitx: hw: avmute set to 1 [01/06, 09:43:50] [ 0.635406@0] vout: vout1: register server: hdmitx_vout_server [01/06, 09:43:50] [ 0.635417@0] vout: vout2: register server: hdmitx_vout2_server [01/06, 09:43:50] [ 0.636115@0] hdmitx: system: fmt_attr 444,8bit [01/06, 09:43:50] [ 0.636202@0] hdmitx: system: amhdmitx_probe end [01/06, 09:43:50] [ 0.637156@0] vout: create vout attribute OK [01/06, 09:43:50] [ 0.637380@0] vout: vout_fops_create OK [01/06, 09:43:50] [ 0.637392@0] vout: vout1: register server: nulldisp_vout_server [01/06, 09:43:50] [ 0.637521@0] hdmitx: hdmitx_set_current_vmode[5008] [01/06, 09:43:50] [ 0.637532@0] hdmitx: system: recalc before 1080p60hz 60 1 [01/06, 09:43:50] [ 0.637544@0] hdmitx: system: recalc after 1080p60hz 2997 50 [01/06, 09:43:50] [ 0.637553@0] hdmitx: alread display in uboot [01/06, 09:43:50] [ 0.637563@0] vout: init mode 1080p60hz set ok [01/06, 09:43:50] [ 0.637572@0] vout: aml_vout_probe OK [01/06, 09:43:50] [ 0.638654@0] chip type:0x28 [01/06, 09:43:50] [ 0.639187@0] MEMORY:[100000+bff00000] [01/06, 09:43:50] [ 0.639203@0] ramdump_probe, storage device:data [01/06, 09:43:50] [ 0.639213@0] NO valid ramdump args:0 0 [01/06, 09:43:50] [ 0.639237@0] ramdump_probe, set sticky to 8c00 [01/06, 09:43:50] [ 0.639712@0] Advanced Linux Sound Architecture Driver Initialized. [01/06, 09:43:50] [ 0.640264@0] Bluetooth: Core ver 2.22 [01/06, 09:43:50] [ 0.640319@0] NET: Registered protocol family 31 [01/06, 09:43:50] [ 0.640330@0] Bluetooth: HCI device and connection manager initialized [01/06, 09:43:50] [ 0.640348@0] Bluetooth: HCI socket layer initialized [01/06, 09:43:50] [ 0.640364@0] Bluetooth: L2CAP socket layer initialized [01/06, 09:43:50] [ 0.640410@0] Bluetooth: SCO socket layer initialized [01/06, 09:43:50] [ 0.640970@0] NetLabel: Initializing [01/06, 09:43:50] [ 0.640984@0] NetLabel: domain hash size = 128 [01/06, 09:43:50] [ 0.640994@0] NetLabel: protocols = UNLABELED CIPSOv4 [01/06, 09:43:50] [ 0.641052@0] NetLabel: unlabeled traffic allowed by default [01/06, 09:43:50] [ 0.657239@0] clocksource: Switched to clocksource arch_sys_counter [01/06, 09:43:50] [ 0.747983@3] VFS: Disk quotas dquot_6.6.0 [01/06, 09:43:50] [ 0.748091@3] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [01/06, 09:43:50] [ 0.761583@1] dtv_dmd:[amldtvdemod..]aml_dtvdemod_init. [01/06, 09:43:50] [ 0.762355@1] NET: Registered protocol family 2 [01/06, 09:43:50] [ 0.764530@2] hdmitx: edid: EDID Parser: [01/06, 09:43:50] [ 0.764550@2] hdmitx: hdmitx: reach vesa idx MAX [01/06, 09:43:50] [ 0.764560@2] hdmitx: hdmitx: reach vesa idx MAX [01/06, 09:43:50] [ 0.764570@2] hdmitx: hdmitx: reach vesa idx MAX [01/06, 09:43:50] [ 0.764587@2] hdmitx: hdmitx: reach vesa idx MAX [01/06, 09:43:50] [ 0.764599@2] hdmitx: hdmitx: reach vesa idx MAX [01/06, 09:43:50] [ 0.764616@2] [RX]-up_phy_addr = 1 [01/06, 09:43:50] [ 0.764636@2] hdmitx: edid: get dtd0 vic: 3 [01/06, 09:43:50] [ 0.764648@2] hdmitx: hdmitx: get PMT vic: 16 [01/06, 09:43:50] [ 0.764660@2] hdmitx: edid: find IEEEOUT [01/06, 09:43:50] [ 0.764697@2] hdmitx: edid: check sum valid [01/06, 09:43:50] [ 0.764707@2] hdmitx: edid: check sum valid [01/06, 09:43:50] [ 0.764720@2] hdmitx: edid: check sum valid [01/06, 09:43:50] [ 0.764730@2] hdmitx: edid: check sum valid [01/06, 09:43:50] [ 0.764742@2] hdmitx: edid: blk0 raw data [01/06, 09:43:50] [ 0.764785@2] hdmitx: edid: [01/06, 09:43:50] [ 0.764785@2] 00ffffffffffff0056044348010000001416010380462778eb007c0057490025 [01/06, 09:43:50] [ 0.764785@2] 11484befcf80a9c081598180615945593159311931d9023a801871382d40582c [01/06, 09:43:50] [ 0.764785@2] 4500132a2100001e8c0ad08a20e02d10103e9600c48e21000018000000fd0019 [01/06, 09:43:50] [ 0.764785@2] 5a195b10000a202020202020000000fc004c4344383031000a20202020200169 [01/06, 09:43:50] [ 0.764785@2] [01/06, 09:43:50] [ 0.764785@2] [01/06, 09:43:50] [ 0.764807@2] hdmitx: edid: blk1 raw data [01/06, 09:43:50] [ 0.764850@2] hdmitx: edid: [01/06, 09:43:50] [ 0.764850@2] 020329f14d0703161204130514901f21222023097f0723047f0c23057f0c8301 [01/06, 09:43:50] [ 0.764850@2] 000066030c001000808c0ad08a20e02d10103e9600c48e21000018011d801871 [01/06, 09:43:50] [ 0.764850@2] 1c1620582c2500c48e2100009e00000000000000000000000000000000000000 [01/06, 09:43:50] [ 0.764850@2] 00000000000000000000000000000000000000000000000000000000000000b1 [01/06, 09:43:50] [ 0.764850@2] [01/06, 09:43:50] [ 0.764850@2] [01/06, 09:43:50] [ 0.764873@2] hdmitx: system: update rx hdr info 0 [01/06, 09:43:50] [ 0.765324@2] hdmitx: hdmitx: get vic 0 cscd 444,8bit [01/06, 09:43:50] [ 0.765346@2] hdmitx: hdmitx: mode name null [01/06, 09:43:50] [ 0.765372@2] hdmitx: hdmitx: tmds clock 0 [01/06, 09:43:50] [ 0.765382@2] hdmitx: hdmitx: act clock: 0 [01/06, 09:43:50] [ 0.765467@0] hdmitx: system: irq 2 0 [01/06, 09:43:50] [ 0.781638@1] TCP established hash table entries: 32768 (order: 6, 262144 bytes) [01/06, 09:43:50] [ 0.781868@1] TCP bind hash table entries: 32768 (order: 7, 524288 bytes) [01/06, 09:43:50] [ 0.782331@1] TCP: Hash tables configured (established 32768 bind 32768) [01/06, 09:43:50] [ 0.782445@1] UDP hash table entries: 2048 (order: 4, 65536 bytes) [01/06, 09:43:50] [ 0.782532@1] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes) [01/06, 09:43:50] [ 0.782808@1] NET: Registered protocol family 1 [01/06, 09:43:50] [ 0.782849@1] PCI: CLS 0 bytes, default 64 [01/06, 09:43:50] [ 0.784853@1] aml_wifi wifi: [wifi_dev_probe] no interrupt pin [01/06, 09:43:50] [ 0.784874@1] aml_wifi wifi: [wifi_dev_probe] no power_on_pin [01/06, 09:43:50] [ 0.784887@1] wifi: power_on_pin_OD = 0; [01/06, 09:43:50] [ 0.784900@1] aml_wifi wifi: [wifi_dev_probe] no power_on_pin2 [01/06, 09:43:50] [ 0.785036@1] aml_wifi wifi: [pwm_double_channel_conf_dt] wifi pwm dt ok [01/06, 09:43:50] [ 0.785059@1] aml_wifi wifi: [pwm_double_channel_conf] wifi pwm conf ok [01/06, 09:43:50] [ 0.785072@1] aml_wifi wifi: [wifi_dev_probe] dhd_static_buf setup [01/06, 09:43:50] [ 0.785083@1] Wifi: bcmdhd_init_wlan_mem: bcmdhd_init_wlan_mem(): 100.10.545.3 [01/06, 09:43:50] [ 0.785691@1] Wifi: bcmdhd_init_wlan_mem: bcmdhd_init_wlan_mem prealloc ok [01/06, 09:43:50] [ 0.785709@1] aml_wifi wifi: [wifi_dev_probe] interrupt_pin=0 [01/06, 09:43:50] [ 0.785721@1] aml_wifi wifi: [wifi_dev_probe] irq_num=0, irq_trigger_type=0 [01/06, 09:43:51] [ 0.785733@1] aml_wifi wifi: [wifi_dev_probe] power_on_pin=0 [01/06, 09:43:51] [ 0.785744@1] aml_wifi wifi: [wifi_dev_probe] clock_32k_pin=0 [01/06, 09:43:51] [ 0.786033@1] aml_wifi wifi: [wifi_setup_dt] wifi_setup_dt [01/06, 09:43:51] [ 0.787470@1] Trying to unpack rootfs image as initramfs... [01/06, 09:43:51] [ 1.133299@2] Freeing initrd memory: 13028K [01/06, 09:43:51] [ 1.137866@2] hw perfevents: clusterb_enabled = 0 [01/06, 09:43:51] [ 1.137889@2] hw perfevents: cpumasks 0xf, 0x0 [01/06, 09:43:51] [ 1.137931@2] hw perfevents: cluster A irq = 10 [01/06, 09:43:51] [ 1.138046@2] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available [01/06, 09:43:51] [ 1.141609@3] audit: initializing netlink subsys (disabled) [01/06, 09:43:51] [ 1.141712@3] audit: type=2000 audit(1.072:1): initialized [01/06, 09:43:51] [ 1.143056@1] workingset: timestamp_bits=45 max_order=20 bucket_order=0 [01/06, 09:43:51] [ 1.149559@0] clear:80800000, free:80800000, tick:478808 us [01/06, 09:43:51] [ 1.155481@1] squashfs: version 4.0 (2009/01/31) Phillip Lougher [01/06, 09:43:51] [ 1.155780@1] exFAT: Version 1.2.9 [01/06, 09:43:51] [ 1.156695@1] Registering sdcardfs 0.1 [01/06, 09:43:51] [ 1.157105@1] ntfs: driver 2.1.32 [Flags: R/O]. [01/06, 09:43:51] [ 1.157481@1] jffs2: version 2.2. (NAND) (SUMMARY) 짤 2001-2006 Red Hat, Inc. [01/06, 09:43:51] [ 1.158227@1] fuse init (API version 7.26) [01/06, 09:43:51] [ 1.159077@1] SELinux: Registering netfilter hooks [01/06, 09:43:51] [ 1.162967@1] NET: Registered protocol family 38 [01/06, 09:43:51] [ 1.162989@1] Key type asymmetric registered [01/06, 09:43:51] [ 1.163001@1] Asymmetric key parser 'x509' registered [01/06, 09:43:51] [ 1.163273@1] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) [01/06, 09:43:51] [ 1.163288@1] io scheduler noop registered (default) [01/06, 09:43:51] [ 1.163299@1] io scheduler deadline registered [01/06, 09:43:51] [ 1.163485@1] io scheduler cfq registered [01/06, 09:43:51] [ 1.174280@2] random: fast init done [01/06, 09:43:51] [ 1.174334@2] random: crng init done [01/06, 09:43:51] [ 1.174665@1] Unable to detect cache hierarchy for CPU 0 [01/06, 09:43:51] [ 1.185066@1] brd: module loaded [01/06, 09:43:51] [ 1.196906@1] loop: module loaded [01/06, 09:43:51] [ 1.197722@1] zram: Added device: zram0 [01/06, 09:43:51] [ 1.198097@1] mtdoops: mtd device (mtddev=name/number) must be supplied [01/06, 09:43:51] [ 1.199135@1] libphy: Fixed MDIO Bus: probed [01/06, 09:43:51] [ 1.199439@1] tun: Universal TUN/TAP device driver, 1.6 [01/06, 09:43:51] [ 1.199450@1] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com> [01/06, 09:43:51] [ 1.200408@1] REG0:Addr = 00000000ad64b6ed [01/06, 09:43:51] [ 1.200431@1] ee eth reset:Addr = 000000000e3e0f1f [01/06, 09:43:51] [ 1.200452@1] read auto_cali_idx fail [01/06, 09:43:51] [ 1.200466@1] Not set cali_val for REG1 [01/06, 09:43:51] [ 1.200962@1] meson6-dwmac ff3f0000.ethernet: no reset control found [01/06, 09:43:51] [ 1.200976@1] stmmac - user ID: 0x11, Synopsys ID: 0x37 [01/06, 09:43:51] [ 1.200985@1] Ring mode enabled [01/06, 09:43:51] [ 1.200995@1] DMA HW capability register supported [01/06, 09:43:51] [ 1.201005@1] Normal descriptors [01/06, 09:43:51] [ 1.201014@1] RX Checksum Offload Engine supported [01/06, 09:43:51] [ 1.201023@1] COE Type 2 [01/06, 09:43:51] [ 1.201032@1] TX Checksum insertion supported [01/06, 09:43:51] [ 1.201041@1] Wake-Up On Lan supported [01/06, 09:43:51] [ 1.201098@1] eth%d: device MAC address ec:5c:68:1d:bc:cc [01/06, 09:43:51] [ 1.201109@1] Enable RX Mitigation via HW Watchdog Timer [01/06, 09:43:51] [ 1.204301@1] libphy: stmmac: probed [01/06, 09:43:51] [ 1.204315@1] eth%d: PHY ID 001cc916 at 0 IRQ POLL (stmmac-0:00) active [01/06, 09:43:51] [ 1.204327@1] eth%d: PHY ID 001cc916 at 1 IRQ POLL (stmmac-0:01) [01/06, 09:43:51] [ 1.205919@1] PPP generic driver version 2.4.2 [01/06, 09:43:51] [ 1.206147@1] PPP BSD Compression module registered [01/06, 09:43:51] [ 1.206160@1] PPP Deflate Compression module registered [01/06, 09:43:51] [ 1.206186@1] PPP MPPE Compression module registered [01/06, 09:43:51] [ 1.206197@1] NET: Registered protocol family 24 [01/06, 09:43:51] [ 1.206225@1] PPTP driver version 0.8.5 [01/06, 09:43:51] [ 1.206633@1] usbcore: registered new interface driver r8152 [01/06, 09:43:51] [ 1.206708@1] usbcore: registered new interface driver asix [01/06, 09:43:51] [ 1.206802@1] usbcore: registered new interface driver ax88179_178a [01/06, 09:43:51] [ 1.206867@1] usbcore: registered new interface driver cdc_ether [01/06, 09:43:51] [ 1.206927@1] usbcore: registered new interface driver net1080 [01/06, 09:43:51] [ 1.206990@1] usbcore: registered new interface driver cdc_subset [01/06, 09:43:51] [ 1.207055@1] usbcore: registered new interface driver zaurus [01/06, 09:43:51] [ 1.207132@1] usbcore: registered new interface driver cdc_ncm [01/06, 09:43:51] [ 1.207874@1] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [01/06, 09:43:51] [ 1.207886@1] ehci-pci: EHCI PCI platform driver [01/06, 09:43:51] [ 1.207956@1] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [01/06, 09:43:51] [ 1.207990@1] ohci-pci: OHCI PCI platform driver [01/06, 09:43:51] [ 1.208599@1] usbcore: registered new interface driver cdc_acm [01/06, 09:43:51] [ 1.208610@1] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters [01/06, 09:43:51] [ 1.208693@1] usbcore: registered new interface driver usb-storage [01/06, 09:43:51] [ 1.208817@1] usbcore: registered new interface driver usbserial [01/06, 09:43:51] [ 1.209267@1] mousedev: PS/2 mouse device common for all mice [01/06, 09:43:51] [ 1.209553@1] usbcore: registered new interface driver xpad [01/06, 09:43:51] [ 1.209704@1] i2c /dev entries driver [01/06, 09:43:51] [ 1.210016@1] IR NEC protocol handler initialized [01/06, 09:43:51] [ 1.210028@1] IR RC5(x/sz) protocol handler initialized [01/06, 09:43:51] [ 1.210038@1] IR RC6 protocol handler initialized [01/06, 09:43:51] [ 1.210048@1] IR JVC protocol handler initialized [01/06, 09:43:51] [ 1.210058@1] IR Sony protocol handler initialized [01/06, 09:43:51] [ 1.210068@1] IR SANYO protocol handler initialized [01/06, 09:43:51] [ 1.210078@1] IR Sharp protocol handler initialized [01/06, 09:43:51] [ 1.210088@1] IR MCE Keyboard/mouse protocol handler initialized [01/06, 09:43:51] [ 1.210098@1] IR XMP protocol handler initialized [01/06, 09:43:51] [ 1.210191@1] usbcore: registered new interface driver uvcvideo [01/06, 09:43:51] [ 1.210202@1] USB Video Class driver (1.1.1) [01/06, 09:43:51] [ 1.210277@1] usbcore: registered new interface driver cx231xx [01/06, 09:43:51] [ 1.210290@1] md: linear personality registered for level -1 [01/06, 09:43:51] [ 1.210729@1] device-mapper: ioctl: 4.35.0-ioctl (2016-06-23) initialised: dm-devel@redhat.com [01/06, 09:43:51] [ 1.210879@1] device-mapper: verity-avb: AVB error handler initialized with vbmeta device: /dev/block/vbmeta [01/06, 09:43:51] [ 1.210893@1] Bluetooth: HCI UART driver ver 2.3 [01/06, 09:43:51] [ 1.210905@1] Bluetooth: HCI UART protocol H4 registered [01/06, 09:43:51] [ 1.213453@0] ledtrig-cpu: registered to indicate activity on CPUs [01/06, 09:43:51] [ 1.214054@0] hidraw: raw HID events driver (C) Jiri Kosina [01/06, 09:43:51] [ 1.214531@0] usbcore: registered new interface driver usbhid [01/06, 09:43:51] [ 1.214544@0] usbhid: USB HID core driver [01/06, 09:43:51] [ 1.214732@0] ashmem: initialized [01/06, 09:43:51] [ 1.215952@0] meson_cpufreq_init: ignor dsu clk! [01/06, 09:43:51] [ 1.215969@0] meson_cpufreq_init: ignor dsu pre parent clk! [01/06, 09:43:51] [ 1.216025@0] value of voltage_tolerance 0 [01/06, 09:43:51] [ 1.216037@0] meson_cpufreq_init:don't find the node <dynamic_gp1_clk> [01/06, 09:43:51] [ 1.216046@0] value of gp1_clk_target 0 [01/06, 09:43:51] [ 1.217446@0] cpu cpu0: meson_cpufreq_init: CPU 0 initialized [01/06, 09:43:51] [ 1.218905@0] ff803000.serial: clock gate not found [01/06, 09:43:51] [ 1.218957@0] meson_uart ff803000.serial: ==uart0 reg addr = 0000000061954273 [01/06, 09:43:51] [ 1.219003@0] ff803000.serial: ttyS0 at MMIO 0xff803000 (irq = 22, base_baud = 1500000) is a meson_uart [01/06, 09:43:51] [ 1.219021@0] meson_uart ff803000.serial: ttyS0 use xtal(24M) 24000000 change 0 to 115200 [01/06, 09:43:51] [ 1.297679@0] hdmitx: system: plugin [01/06, 09:43:51] [ 1.440868@0] hdmitx: edid: EDID Parser: [01/06, 09:43:51] [ 1.440872@0] hdmitx: hdmitx: reach vesa idx MAX [01/06, 09:43:51] [ 1.440874@0] hdmitx: hdmitx: reach vesa idx MAX [01/06, 09:43:51] [ 1.440875@0] hdmitx: hdmitx: reach vesa idx MAX [01/06, 09:43:51] [ 1.440889@0] hdmitx: hdmitx: reach vesa idx MAX [01/06, 09:43:51] [ 1.440892@0] hdmitx: hdmitx: reach vesa idx MAX [01/06, 09:43:51] [ 1.440904@0] [RX]-up_phy_addr = 1 [01/06, 09:43:51] [ 1.440917@0] hdmitx: edid: get dtd0 vic: 3 [01/06, 09:43:51] [ 1.440921@0] hdmitx: hdmitx: get PMT vic: 16 [01/06, 09:43:51] [ 1.440924@0] hdmitx: edid: find IEEEOUT [01/06, 09:43:51] [ 1.440946@0] hdmitx: edid: check sum valid [01/06, 09:43:51] [ 1.440948@0] hdmitx: edid: check sum valid [01/06, 09:43:51] [ 1.440951@0] hdmitx: edid: check sum valid [01/06, 09:43:51] [ 1.440953@0] hdmitx: edid: check sum valid [01/06, 09:43:51] [ 1.440956@0] hdmitx: edid: blk0 raw data [01/06, 09:43:51] [ 1.440981@0] hdmitx: edid: [01/06, 09:43:51] [ 1.440981@0] 00ffffffffffff0056044348010000001416010380462778eb007c0057490025 [01/06, 09:43:51] [ 1.440981@0] 11484befcf80a9c081598180615945593159311931d9023a801871382d40582c [01/06, 09:43:51] [ 1.440981@0] 4500132a2100001e8c0ad08a20e02d10103e9600c48e21000018000000fd0019 [01/06, 09:43:51] [ 1.440981@0] 5a195b10000a202020202020000000fc004c4344383031000a20202020200169 [01/06, 09:43:51] [ 1.440981@0] [01/06, 09:43:51] [ 1.440981@0] [01/06, 09:43:51] [ 1.440983@0] hdmitx: edid: blk1 raw data [01/06, 09:43:51] [ 1.441006@0] hdmitx: edid: [01/06, 09:43:51] [ 1.441006@0] 020329f14d0703161204130514901f21222023097f0723047f0c23057f0c8301 [01/06, 09:43:51] [ 1.441006@0] 000066030c001000808c0ad08a20e02d10103e9600c48e21000018011d801871 [01/06, 09:43:51] [ 1.441006@0] 1c1620582c2500c48e2100009e00000000000000000000000000000000000000 [01/06, 09:43:51] [ 1.441006@0] 00000000000000000000000000000000000000000000000000000000000000b1 [01/06, 09:43:51] [ 1.441006@0] [01/06, 09:43:51] [ 1.441006@0] [01/06, 09:43:51] [ 1.441010@0] hdmitx: system: update physcial size: 700 390 [01/06, 09:43:51] [ 1.441016@0] hdmitx: hw: set audio [01/06, 09:43:51] [ 1.441021@0] hdmitx: hw: hdmitx tx_aud_src = 0 [01/06, 09:43:51] [ 1.441033@0] hdmitx: fs = 0, cd = 4, tmds_clk = 148352 [01/06, 09:43:51] [ 1.441036@0] hdmitx: hw: aud_n_para = 5824 [01/06, 09:43:51] [ 1.441044@0] hdmitx: hw: set channel status [01/06, 09:43:51] [ 4.023600@2] console [ttyS0] enabled [01/06, 09:43:51] [ 4.027789@2] meson_uart ffd24000.serial: ==uart1 reg addr = 00000000ea8e9fe2 [01/06, 09:43:51] [ 4.034380@2] ffd24000.serial: ttyS1 at MMIO 0xffd24000 (irq = 32, base_baud = 1500000) is a meson_uart [01/06, 09:43:51] [ 4.045108@2] amlogic-new-usb2-v2 ffe09000.usb2phy: USB2 phy probe:phy_mem:0xffe09000, iomap phy_base:0xffffff80086d2000 [01/06, 09:43:51] [ 4.054840@2] amlogic-new-usb3-v2 ffe09080.usb3phy: set of GPIOC_7(459) as OTG ID pin [01/06, 09:43:51] [ 4.062358@2] amlogic-new-usb3-v2 ffe09080.usb3phy: ok to acquire gpio [01/06, 09:43:51] [ 4.068879@2] amlogic-new-usb3-v2 ffe09080.usb3phy: USB3 phy probe:phy_mem:0xffe09080, iomap phy_base:0xffffff80086dd080 [01/06, 09:43:51] [ 4.082947@2] aml_dma ff63e000.aml_dma: Aml dma [01/06, 09:43:51] [ 4.085991@2] aml_aes_dma ff63e000.aml_dma:aml_aes: Aml AES_dma [01/06, 09:43:51] [ 4.090682@2] aml_tdes_dma ff63e000.aml_dma:aml_tdes: Aml TDES_dma [01/06, 09:43:51] [ 4.097859@2] aml_sha_dma ff63e000.aml_dma:aml_sha: Aml SHA1/SHA224/SHA256 dma [01/06, 09:43:51] [ 4.106135@2] ion_dev soc:ion_dev: assigned reserved memory node linux,ion-dev [01/06, 09:43:51] [ 4.111090@2] ge2d: ge2d_init_module [01/06, 09:43:51] [ 4.114405@2] ge2d: ge2d_dev major:238 [01/06, 09:43:51] [ 4.118204@2] ge2d: clock source clk_ge2d_gate 000000005ea9c22e [01/06, 09:43:51] [ 4.123803@2] ge2d: clock clk_ge2d source 00000000f7cc6f01 [01/06, 09:43:51] [ 4.129246@2] ge2d: clock source clk_vapb_0 00000000bce17754 [01/06, 09:43:51] [ 4.134872@2] ge2d: ge2d init clock is 500000000 HZ, VPU clock is 666666656 HZ [01/06, 09:43:51] [ 4.142132@2] ge2d: ge2d clock is 499 MHZ [01/06, 09:43:51] [ 4.146060@2] ge2d: find address resource [01/06, 09:43:51] [ 4.150041@2] ge2d: map io source 0x000000007ac41167,size=65536 to 0x0000000060f87678 [01/06, 09:43:51] [ 4.157836@2] ge2d: reserved mem init failed [01/06, 09:43:51] [ 4.162079@2] ge2d: ge2d: pdev=00000000fc621e92, irq=38, clk=000000005ea9c22e [01/06, 09:43:51] [ 4.169251@2] ge2d: ge2d start monitor [01/06, 09:43:51] [ 4.173198@3] ge2d: ge2d workqueue monitor start [01/06, 09:43:51] [ 4.173620@2] [tsync_pcr_init]init success. [01/06, 09:43:51] [ 4.173936@2] amvideom vsync irq: 39 [01/06, 09:43:51] [ 4.173974@2] create_ge2d_work_queue video task ok [01/06, 09:43:51] [ 4.174581@2] create_video_receiver video_render.0 0000000031659fab, path_id:2 success [01/06, 09:43:51] [ 4.174584@2] create_video_receiver video_render.1 00000000f2d52c85, path_id:3 success [01/06, 09:43:51] [ 4.174657@2] hdmitx: hdcp: hdmitx_hdcp_init [01/06, 09:43:51] [ 4.175298@2] vout: vout2: create vout2 attribute OK [01/06, 09:43:51] [ 4.175415@2] vout: vout2: vout2_fops_create OK [01/06, 09:43:51] [ 4.175630@2] vout: vout2: clktree_init [01/06, 09:43:51] [ 4.175633@2] vout: vout2: register server: nulldisp_vout2_server [01/06, 09:43:51] [ 4.175743@2] vout: vout2: init mode null set ok [01/06, 09:43:51] [ 4.175746@2] vout: vout2: aml_vout2_probe OK [01/06, 09:43:51] [ 4.175891@2] DI: di_module_init ok. [01/06, 09:43:51] [ 4.176121@2] dim:dim_module_init [01/06, 09:43:51] [ 4.176383@2] dim:dim_probe: [01/06, 09:43:51] [ 4.176396@2] dim:dim_probe: major 235 [01/06, 09:43:51] [ 4.176471@2] di_que_alloc:ok [01/06, 09:43:51] [ 4.176482@2] di_que_alloc:ok [01/06, 09:43:51] [ 4.176490@2] dim:qbuf_int:qbuf_blk:end [01/06, 09:43:51] [ 4.176500@2] dim:qbuf_int:qbuf_mem:end [01/06, 09:43:51] [ 4.176505@2] dim:qbuf_int:qbuf_pat:end [01/06, 09:43:52] [ 4.176511@2] dim:qbuf_int:qbuf_iat:end [01/06, 09:43:52] [ 4.176518@2] dim:qbuf_int:qbuf_blk:end [01/06, 09:43:52] [ 4.176524@2] dim:qbuf_int:qbuf_mem:end [01/06, 09:43:52] [ 4.176531@2] dim:qbuf_int:qbuf_pat:end [01/06, 09:43:52] [ 4.176537@2] dim:qbuf_int:qbuf_iat:end [01/06, 09:43:52] [ 4.176540@2] dim:di_cfg_top_init_val: [01/06, 09:43:52] [ 4.176544@2] dim:di_cfg_top_init_val:finish [01/06, 09:43:52] [ 4.176559@2] dev_vframe_init finish [01/06, 09:43:52] [ 4.177299@2] dim:match name: dim_g12a:id[16] [01/06, 09:43:52] [ 4.177412@2] di_multi multi-di: assigned reserved memory node linux,di_cma [01/06, 09:43:52] [ 4.177415@2] dim:di_cfg_top_dts [01/06, 09:43:52] [ 4.177419@2] dim: flag_cma:1 [01/06, 09:43:52] [ 4.177428@2] dim:di_cfg_top_dts:en_dat:0x0 [01/06, 09:43:52] [ 4.177433@2] dim:mem size from dts:0xc000000 [01/06, 09:43:52] [ 4.177458@2] dim:pre_irq:52 [01/06, 09:43:52] [ 4.177468@2] dim:post_irq:53 [01/06, 09:43:52] [ 4.177470@2] DI: dim_probe allocate rdma channel 0. [01/06, 09:43:52] [ 4.177479@2] dim:err:dim_get_vpu_clkb: get clk vpu error. [01/06, 09:43:52] [ 4.177482@2] DI: vpu clkb <334000000, 667000000> [01/06, 09:43:52] [ 4.177512@2] dim:vpu clkb =666666656. [01/06, 09:43:52] [ 4.177602@2] DI: afbc_prob:ver[2],_g12a [01/06, 09:43:52] [ 4.177617@2] 0x000000e1:Y=e1,U=0,V=0 [01/06, 09:43:52] [ 4.177619@2] 0x000000e2:Y=e2,U=0,V=0 [01/06, 09:43:52] [ 4.177621@2] 0x000000e3:Y=e3,U=0,V=0 [01/06, 09:43:52] [ 4.177623@2] 0x000000f0:Y=f0,U=0,V=0 [01/06, 09:43:52] [ 4.177625@2] 0x000000f1:Y=f1,U=0,V=0 [01/06, 09:43:52] [ 4.177627@2] 0x000000f2:Y=f2,U=0,V=0 [01/06, 09:43:52] [ 4.177629@2] 0x000000f3:Y=f3,U=0,V=0 [01/06, 09:43:52] [ 4.177631@2] 0x000000f4:Y=f4,U=0,V=0 [01/06, 09:43:52] [ 4.177632@2] 0x000000f5:Y=f5,U=0,V=0 [01/06, 09:43:52] [ 4.177634@2] 0x000000f6:Y=f6,U=0,V=0 [01/06, 09:43:52] [ 4.177642@2] 0x000000f7:Y=f7,U=0,V=0 [01/06, 09:43:52] [ 4.177643@2] 0x000000f8:Y=f8,U=0,V=0 [01/06, 09:43:52] [ 4.177645@2] 0x000000f9:Y=f9,U=0,V=0 [01/06, 09:43:52] [ 4.177647@2] 0x000000fa:Y=fa,U=0,V=0 [01/06, 09:43:52] [ 4.177649@2] 0x000000fb:Y=fb,U=0,V=0 [01/06, 09:43:52] [ 4.177651@2] 0x000000fc:Y=fc,U=0,V=0 [01/06, 09:43:52] [ 4.177659@2] 0x000000fd:Y=fd,U=0,V=0 [01/06, 09:43:52] [ 4.177661@2] 0x000000fe:Y=fe,U=0,V=0 [01/06, 09:43:52] [ 4.177663@2] 0x000000ff:Y=ff,U=0,V=0 [01/06, 09:43:52] [ 4.177665@2] 0x0000003a:Y=3a,U=0,V=0 [01/06, 09:43:52] [ 4.177666@2] 0x0000003b:Y=3b,U=0,V=0 [01/06, 09:43:52] [ 4.177669@2] 0x0000003c:Y=3c,U=0,V=0 [01/06, 09:43:52] [ 4.177673@2] 0x0000003d:Y=3d,U=0,V=0 [01/06, 09:43:52] [ 4.177675@2] 0x0000003e:Y=3e,U=0,V=0 [01/06, 09:43:52] [ 4.177677@2] 0x0000003f:Y=3f,U=0,V=0 [01/06, 09:43:52] [ 4.177679@2] DI: support multi decoding 0x3d~0x3e~0x3f. [01/06, 09:43:52] [ 4.177795@2] dim:dcntr_prob [01/06, 09:43:52] [ 4.177888@2] . [01/06, 09:43:52] [ 4.177984@2] . [01/06, 09:43:52] [ 4.177984@2] dim_probe:ok [01/06, 09:43:52] [ 4.178080@2] dim:dim_module_init finish [01/06, 09:43:52] [ 4.178084@2] dil:dil_init. [01/06, 09:43:52] [ 4.178411@2] dil:dil_init ok. [01/06, 09:43:52] [ 4.178443@2] vdin_drv_init: major 511 [01/06, 09:43:52] [ 4.181805@2] rdma_register, rdma_table_addr 00000000df74e0a3 rdma_table_addr_phy b704a000 reg_buf 00000000d62d5bde [01/06, 09:43:52] [ 4.181807@2] rdma_register success, handle 3 table_size 512 [01/06, 09:43:52] [ 4.181810@2] vdin_drv_probe:vdin.0 rdma hanld 3. [01/06, 09:43:52] [ 4.182125@2] vdin vdin0: assigned reserved memory node linux,vdin0_cma [01/06, 09:43:52] [ 4.182128@2] [01/06, 09:43:52] [ 4.182128@2] vdin memory resource done. [01/06, 09:43:52] [ 4.182132@2] vdin0 cma_mem_size = 64 MB [01/06, 09:43:52] [ 4.182137@2] vdin0 irq: 36 rdma irq: 2 [01/06, 09:43:52] [ 4.182142@2] set_canvas_manual = 0 [01/06, 09:43:52] [ 4.182169@2] get fclk_div5 err [01/06, 09:43:52] [ 4.182172@2] vdin_drv_probe: vdin cannot get msr clk !!! [01/06, 09:43:52] [ 4.182204@2] vdin_drv_probe: driver initialized ok [01/06, 09:43:52] [ 4.182315@2] rdma_register, rdma_table_addr 00000000cdf32c8e rdma_table_addr_phy b704b000 reg_buf 000000007ffeb591 [01/06, 09:43:52] [ 4.182317@2] rdma_register success, handle 4 table_size 512 [01/06, 09:43:52] [ 4.182319@2] vdin_drv_probe:vdin.1 rdma hanld 4. [01/06, 09:43:52] [ 4.182522@2] vdin vdin1: assigned reserved memory node linux,vdin1_cma [01/06, 09:43:52] [ 4.182523@2] [01/06, 09:43:52] [ 4.182523@2] vdin memory resource done. [01/06, 09:43:52] [ 4.182527@2] vdin1 cma_mem_size = 64 MB [01/06, 09:43:52] [ 4.182531@2] vdin1 irq: 37 rdma irq: 4 [01/06, 09:43:52] [ 4.182534@2] set_canvas_manual = 0 [01/06, 09:43:52] [ 4.182552@2] get fclk_div5 err [01/06, 09:43:52] [ 4.182555@2] vdin_drv_probe: vdin cannot get msr clk !!! [01/06, 09:43:52] [ 4.182561@2] vdin_debugfs_init only support debug vdin0 1 [01/06, 09:43:52] [ 4.182563@2] vdin_drv_probe: driver initialized ok [01/06, 09:43:52] [ 4.182725@2] vdin_drv_init: vdin driver init done [01/06, 09:43:52] [ 4.182728@2] [viuin..]viuin_init_module viuin module init [01/06, 09:43:52] [ 4.183113@2] [viuin..]viuin_probe probe ok. [01/06, 09:43:52] [ 4.183680@2] [RX]-hdmirx: hdmirx_init. [01/06, 09:43:52] [ 4.183683@2] ESM HLD: Initializing... [01/06, 09:43:52] [ 4.184433@2] amvdec_csi module: init. [01/06, 09:43:52] [ 4.184439@2] amvdec_csi_init_module:major 509 [01/06, 09:43:52] [ 4.184619@2] amvdec_csi module: init. ok [01/06, 09:43:52] [ 4.184624@2] amlvid:info: amlvideo_init called [01/06, 09:43:52] [ 4.184624@2] amlvid:info: amlvideo_create_instance called [01/06, 09:43:52] [ 4.184628@2] amlvid:info: v4l2_dev.name=:amlvideo-000 [01/06, 09:43:52] [ 4.184787@2] amlvideo-000: V4L2 device registered as video10 [01/06, 09:43:52] [ 4.184788@2] amlvid:info: amlvideo_create_instance called [01/06, 09:43:52] [ 4.184791@2] amlvid:info: v4l2_dev.name=:amlvideo-001 [01/06, 09:43:52] [ 4.184898@2] amlvideo-001: V4L2 device registered as video23 [01/06, 09:43:52] [ 4.185092@2] amlvideo2 probe called [01/06, 09:43:52] [ 4.185313@2] amlvideo2_create_node [01/06, 09:43:52] [ 4.185313@2] amlvideo2: V4L2 device registered as video11 [01/06, 09:43:52] [ 4.185349@2] amlvideo2 probe called [01/06, 09:43:52] [ 4.185500@2] amlvideo2_create_node [01/06, 09:43:52] [ 4.185500@2] amlvideo2: V4L2 device registered as video12 [01/06, 09:43:52] [ 4.185562@2] PPMGRDRV: warn: ppmgr module init func called [01/06, 09:43:52] [ 4.185777@2] PPMGRDRV: info: ppmgr_driver_probe called [01/06, 09:43:52] [ 4.185886@2] Reserved memory: failed to init DMA memory pool at 0x00000000bf800000, size 0 MiB [01/06, 09:43:52] [ 4.185893@2] PPMGRDRV: info: ppmgr_dev major:508 [01/06, 09:43:52] [ 4.186648@2] ionvideo-000: V4L2 device registered as video13 [01/06, 09:43:52] [ 4.186773@2] ionvideo-001: V4L2 device registered as video14 [01/06, 09:43:52] [ 4.186882@2] ionvideo-002: V4L2 device registered as video15 [01/06, 09:43:52] [ 4.186987@2] ionvideo-003: V4L2 device registered as video16 [01/06, 09:43:52] [ 4.187099@2] ionvideo-004: V4L2 device registered as video17 [01/06, 09:43:52] [ 4.187228@2] ionvideo-005: V4L2 device registered as video18 [01/06, 09:43:52] [ 4.187337@2] ionvideo-006: V4L2 device registered as video19 [01/06, 09:43:52] [ 4.187440@2] ionvideo-007: V4L2 device registered as video20 [01/06, 09:43:52] [ 4.187546@2] ionvideo-008: V4L2 device registered as video21 [01/06, 09:43:52] [ 4.187549@2] ionvid: info: Video Technology Magazine Ion Video [01/06, 09:43:52] [ 4.187551@2] ionvid: info: Capture Board ver 1.0 successfully loaded [01/06, 09:43:52] [ 4.187885@2] picdec: picdec_driver_probe called. [01/06, 09:43:52] [ 4.188154@2] picdec: probe of picdec failed with error -22 [01/06, 09:43:52] [ 4.188361@2] videosync_create_instance dev_s 00000000861ec733,dev_s->dev 0000000031c4636a [01/06, 09:43:52] [ 4.188363@2] videosync_create_instance reg videosync.0 [01/06, 09:43:52] [ 4.188442@0] videosync_thread started [01/06, 09:43:52] [ 4.188687@2] v4lvideo-000: V4L2 device registered as video30 [01/06, 09:43:52] [ 4.188794@2] v4lvideo-001: V4L2 device registered as video31 [01/06, 09:43:52] [ 4.188895@2] v4lvideo-002: V4L2 device registered as video32 [01/06, 09:43:52] [ 4.189014@2] v4lvideo-003: V4L2 device registered as video33 [01/06, 09:43:52] [ 4.189117@2] v4lvideo-004: V4L2 device registered as video34 [01/06, 09:43:52] [ 4.189265@2] v4lvideo-005: V4L2 device registered as video35 [01/06, 09:43:52] [ 4.189379@2] v4lvideo-006: V4L2 device registered as video36 [01/06, 09:43:52] [ 4.189491@2] v4lvideo-007: V4L2 device registered as video37 [01/06, 09:43:52] [ 4.189595@2] v4lvideo-008: V4L2 device registered as video38 [01/06, 09:43:52] [ 4.189598@2] video_composer_module_init_1 [01/06, 09:43:52] [ 4.189907@2] video_composer_probe_3.1:ports[i].name=video_composer.0, i=0 [01/06, 09:43:52] [ 4.190011@0] video_composer_probe_3.1:ports[i].name=video_composer.1, i=1 [01/06, 09:43:52] [ 4.190105@1] video_composer_probe num=2 [01/06, 09:43:52] [ 4.190259@1] videotunnel init [01/06, 09:43:52] [ 4.190693@2] aml_vecm_init:module init [01/06, 09:43:52] [ 4.190936@2] [01/06, 09:43:52] [ 4.190936@2] VECM probe start [01/06, 09:43:52] [ 4.191233@0] Can't find detect_colorbar. [01/06, 09:43:52] [ 4.191236@0] Can't find detect_face. [01/06, 09:43:52] [ 4.191238@0] Can't find detect_corn. [01/06, 09:43:52] [ 4.191240@0] Can't find wb_sel. [01/06, 09:43:52] [ 4.191243@0] hdr:Can't find cfg_en_osd_100. [01/06, 09:43:52] [ 4.191251@0] amlogic, vecm [01/06, 09:43:52] [ 4.191251@0] vlock dt support: 1 [01/06, 09:43:52] [ 4.191253@0] vlock dt new_fsm: 0 [01/06, 09:43:52] [ 4.191255@0] vlock dt hwver: 0 [01/06, 09:43:52] [ 4.191256@0] vlock dt phlock_en: 0 [01/06, 09:43:52] [ 4.191259@0] Can't find vlock_en. [01/06, 09:43:52] [ 4.191262@0] Can't find vlock_mode. [01/06, 09:43:52] [ 4.191264@0] Can't find vlock_pll_m_limit. [01/06, 09:43:52] [ 4.191266@0] Can't find vlock_line_limit. [01/06, 09:43:52] [ 4.191278@0] param_config vlock_en:1 md=0x4 [01/06, 09:43:52] [ 4.191284@0] vlock: maxLine 524,maxPixel 1715 [01/06, 09:43:52] [ 4.191286@0] vlock_status_init vlock_en:1 [01/06, 09:43:52] [ 4.191288@0] pixel_probe: vpp probe func error! [01/06, 09:43:52] [ 4.191309@0] aml_vecm_probe: ok [01/06, 09:43:52] [ 4.191367@0] amdolby_vision_init:module init [01/06, 09:43:52] [ 4.191659@0] [01/06, 09:43:52] [ 4.191659@0] amdolby_vision probe start & ver: 20181220 [01/06, 09:43:52] [ 4.191664@0] [01/06, 09:43:52] [ 4.191664@0] cpu_id=2 tvmode=0 [01/06, 09:43:52] [ 4.191812@1] dolby_vision_init_receiver(dvel) [01/06, 09:43:52] [ 4.191827@1] dolby_vision_init_receiver: dvel [01/06, 09:43:52] [ 4.192046@1] amdolby_vision_probe: ok [01/06, 09:43:52] [ 4.192049@1] g12 dovi disable in uboot [01/06, 09:43:53] [ 4.192107@1] prime_sl module init [01/06, 09:43:53] [ 4.192635@1] vm_init . [01/06, 09:43:53] [ 4.193687@1] meson-mmc: mmc driver version: 3.02, 2017-05-15: New Emmc Host Controller [01/06, 09:43:53] [ 4.194523@0] meson-mmc: >>>>>>>>hostbase 00000000b529a33f, dmode [01/06, 09:43:53] [ 4.194820@0] meson-mmc: actual_clock :400000, HHI_nand: 0x80 [01/06, 09:43:53] [ 4.194823@0] meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000033c [01/06, 09:43:53] [ 4.194866@0] meson-mmc: emmc: fixdiv calc done: adj = 2d2000 [01/06, 09:43:53] [ 4.233251@0] meson-mmc: meson_mmc_probe() : success! [01/06, 09:43:53] [ 4.233509@0] amlogic mtd driver init [01/06, 09:43:53] [ 4.234352@2] aml_vrtc rtc: rtc core: registered aml_vrtc as rtc0 [01/06, 09:43:53] [ 4.234519@2] input: aml_vkeypad as /devices/platform/rtc/input/input0 [01/06, 09:43:53] [ 4.235644@1] cectx ff80023c.aocec: cec driver date:2020/01/22:suspend get phy addr from tx and save [01/06, 09:43:53] [ 4.235644@1] [01/06, 09:43:53] [ 4.235870@2] cectx ff80023c.aocec: compatible:amlogic, aocec-g12a [01/06, 09:43:53] [ 4.235873@2] cectx ff80023c.aocec: cecb_ver:0x1 [01/06, 09:43:53] [ 4.235875@2] cectx ff80023c.aocec: line_reg:0x1 [01/06, 09:43:53] [ 4.235878@2] cectx ff80023c.aocec: line_bit:0x3 [01/06, 09:43:53] [ 4.235880@2] cectx ff80023c.aocec: ee_to_ao:0x1 [01/06, 09:43:53] [ 4.236013@2] input: cec_input as /devices/virtual/input/input1 [01/06, 09:43:53] [ 4.236134@1] cectx ff80023c.aocec: not find 'port_num' [01/06, 09:43:53] [ 4.236138@1] cectx ff80023c.aocec: using cec:0 [01/06, 09:43:53] [ 4.236163@1] cectx ff80023c.aocec: no hdmirx regs [01/06, 09:43:53] [ 4.236166@1] cectx ff80023c.aocec: no hhi regs [01/06, 09:43:53] [ 4.239338@1] irq cnt:2, a:35, b34 [01/06, 09:43:53] [ 4.239622@1] cectx ff80023c.aocec: wakeup_reason:0x0 [01/06, 09:43:53] [ 4.239681@1] cectx ff80023c.aocec: cev val1: 0x0;val2: 0x0 [01/06, 09:43:53] [ 4.239685@1] cectx ff80023c.aocec: aml_cec_probe success end [01/06, 09:43:53] [ 4.240151@1] unifykey: storage in base: 0xffffffc005000000 [01/06, 09:43:53] [ 4.240153@1] unifykey: storage out base: 0xffffffc005040000 [01/06, 09:43:53] [ 4.240155@1] unifykey: storage block base: 0xffffffc005080000 [01/06, 09:43:53] [ 4.240156@1] unifykey: probe done! [01/06, 09:43:53] [ 4.240644@1] unifykey: no efuse-version set, use default value: -1 [01/06, 09:43:53] [ 4.240646@1] unifykey: key unify config unifykey-num is 19 [01/06, 09:43:53] [ 4.240753@1] unifykey: key unify fact unifykey-num is 19 [01/06, 09:43:53] [ 4.240761@1] unifykey: unifykey_devno: 1f500000 [01/06, 09:43:53] [ 4.240971@2] unifykey: device unifykeys created ok [01/06, 09:43:53] [ 4.241024@2] unifykey: aml_unifykeys_init done! [01/06, 09:43:53] [ 4.241126@2] meson ts init [01/06, 09:43:53] [ 4.241286@2] tsensor id: 0 [01/06, 09:43:53] [ 4.241443@2] r1p1_tsensor_read valid cnt is 0 [01/06, 09:43:53] [ 4.241465@2] tsensor trim info: 0xda0080b8! [01/06, 09:43:53] [ 4.241468@2] tsensor hireboot: 0xc0ff2af0 [01/06, 09:43:53] [ 4.241560@2] meson ts init [01/06, 09:43:53] [ 4.241575@2] tsensor id: 1 [01/06, 09:43:53] [ 4.241650@2] r1p1_tsensor_read valid cnt is 0 [01/06, 09:43:53] [ 4.241660@2] tsensor trim info: 0xda008083! [01/06, 09:43:53] [ 4.241662@2] tsensor hireboot: 0xc0ff2ab0 [01/06, 09:43:53] [ 4.241901@2] audio_dsp: [dsp]register dsp to char divece(257) [01/06, 09:43:53] [ 4.242121@1] amaudio: amaudio: driver amaudio init! [01/06, 09:43:53] [ 4.242607@2] amaudio: amaudio_init - amaudio: driver amaudio succuess! [01/06, 09:43:53] [ 4.243604@1] amlkaraoke init success! [01/06, 09:43:53] [ 4.243819@1] sysled: module init [01/06, 09:43:53] [ 4.244143@1] meson_wdt ffd0f0d0.watchdog: start watchdog [01/06, 09:43:53] [ 4.244146@1] meson_wdt ffd0f0d0.watchdog: creat work queue for watch dog [01/06, 09:43:53] [ 4.244458@1] meson_wdt ffd0f0d0.watchdog: AML Watchdog Timer probed done [01/06, 09:43:53] [ 4.245137@2] amlogic rfkill init [01/06, 09:43:53] [ 4.245367@2] enter bt_probe of_node [01/06, 09:43:53] [ 4.245399@2] not get gpio_en [01/06, 09:43:53] [ 4.245406@2] not get gpio_btwakeup [01/06, 09:43:53] [ 4.245412@2] power on valid level is high [01/06, 09:43:53] [ 4.245412@2] bt: power_on_pin_OD = 0; [01/06, 09:43:53] [ 4.245414@2] bt: power_off_flag = 1; [01/06, 09:43:53] [ 4.245416@2] dis power down = 0; [01/06, 09:43:53] [ 4.273363@2] request_irq error ret=-22 [01/06, 09:43:53] [ 4.273407@2] dev_pm_set_wake_irq failed: -22 [01/06, 09:43:53] [ 4.274218@2] dmc_monitor_probe [01/06, 09:43:53] [ 4.274398@2] page_trace_module_init, create sysfs failed [01/06, 09:43:53] [ 4.275391@2] atv_demod: aml_atvdemod_init: OK, atv demod version: V2.15. [01/06, 09:43:53] [ 4.275604@2] defendkey ff630218.defendkey: Reserved memory is not enough! [01/06, 09:43:53] [ 4.275617@2] defendkey: probe of ff630218.defendkey failed with error -22 [01/06, 09:43:53] [ 4.276076@0] bl40: bl40 probe [01/06, 09:43:53] [ 4.276826@2] usbcore: registered new interface driver snd-usb-audio [01/06, 09:43:53] [ 4.278195@2] aml_codec_T9015 ff632000.t9015: aml_T9015_audio_codec_probe [01/06, 09:43:53] [ 4.278224@2] T9015 acodec used by auge, tdmout:1 [01/06, 09:43:53] [ 4.281035@2] asoc debug: aml_audio_controller_probe-130 [01/06, 09:43:53] [ 4.281749@2] aml_tdm_platform_probe, tdm ID = 0, lane_cnt = 4 [01/06, 09:43:53] [ 4.281789@2] snd_tdm ff642000.audiobus:tdma: lane_mask_out = 1, lane_oe_mask_out = 0 [01/06, 09:43:53] [ 4.281847@2] snd_tdm ff642000.audiobus:tdma: neither mclk_pad nor mclk2pad set [01/06, 09:43:53] [ 4.281967@2] aml_tdm_platform_probe(), share en = 1 [01/06, 09:43:53] [ 4.281968@2] No channel mask node Channel_Mask [01/06, 09:43:53] [ 4.282145@2] aml_tdm_platform_probe, tdm ID = 1, lane_cnt = 4 [01/06, 09:43:53] [ 4.282221@2] TDM id 1 samesource_sel:3 [01/06, 09:43:53] [ 4.282229@2] snd_tdm ff642000.audiobus:tdmb: lane_mask_out = 1, lane_oe_mask_out = 0 [01/06, 09:43:53] [ 4.282403@2] TDM id 1 output clk enable:1 [01/06, 09:43:53] [ 4.282434@2] aml_tdm_platform_probe(), share en = 1 [01/06, 09:43:53] [ 4.282434@2] No channel mask node Channel_Mask [01/06, 09:43:53] [ 4.282442@2] TDM id 1 tuning clk enable:1 [01/06, 09:43:53] [ 4.282624@2] aml_tdm_platform_probe, tdm ID = 2, lane_cnt = 4 [01/06, 09:43:53] [ 4.282655@2] snd_tdm ff642000.audiobus:tdmc: lane_mask_out = 1, lane_oe_mask_out = 1 [01/06, 09:43:53] [ 4.282843@2] aml_tdm_platform_probe(), share en = 1 [01/06, 09:43:53] [ 4.282844@2] No channel mask node Channel_Mask [01/06, 09:43:53] [ 4.283479@2] aml_spdif_platform_probe, spdif ID = 0 [01/06, 09:43:53] [ 4.283694@2] snd_spdif ff642000.audiobus:spdif: aml_spdif_get_pins error! [01/06, 09:43:53] [ 4.283810@2] SPDIF id 0 asrc_id:0 auto_asrc:0 [01/06, 09:43:53] [ 4.283887@2] Spdif id 0 tuning clk enable:1 [01/06, 09:43:53] [ 4.283892@2] spdifout_play_with_zerodata, spdif id:0 enable:0 [01/06, 09:43:53] [ 4.283899@2] spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 [01/06, 09:43:53] [ 4.283905@2] spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 [01/06, 09:43:53] [ 4.283911@2] hdmitx: audio: aout notify format CT_PCM [01/06, 09:43:53] [ 4.283914@2] hdmitx: hw: set audio [01/06, 09:43:53] [ 4.283916@2] hdmitx: hw: hdmitx tx_aud_src = 0 [01/06, 09:43:53] [ 4.283933@2] hdmitx: fs = 3, cd = 4, tmds_clk = 148352 [01/06, 09:43:53] [ 4.283935@2] hdmitx: hw: aud_n_para = 5824 [01/06, 09:43:53] [ 4.283944@2] hdmitx: hw: set channel status [01/06, 09:43:53] [ 4.283974@2] hdmitx: audio: Audio Type: PCM [01/06, 09:43:53] [ 4.283976@2] hdmitx: audio: set audio param [01/06, 09:43:53] [ 4.283981@2] spdif_a is set to enable [01/06, 09:43:53] [ 4.283993@2] aml_spdif_platform_probe, register soc platform [01/06, 09:43:53] [ 4.284030@2] aml_spdif_platform_probe, spdif ID = 1 [01/06, 09:43:53] [ 4.284104@2] aml_spdif_platform_probe, register soc platform [01/06, 09:43:53] [ 4.284867@2] default set lane_mask_in as all lanes. [01/06, 09:43:53] [ 4.284870@2] aml_pdm_platform_probe pdm filter mode from dts:1 [01/06, 09:43:53] [ 4.284881@2] aml_pdm_platform_probe, register soc platform [01/06, 09:43:53] [ 4.285392@2] audio-ddr-manager ff642000.audiobus:ddr_manager: 0, irqs toddr 24, frddr 27 [01/06, 09:43:53] [ 4.285420@2] audio-ddr-manager ff642000.audiobus:ddr_manager: 1, irqs toddr 25, frddr 28 [01/06, 09:43:53] [ 4.285447@2] audio-ddr-manager ff642000.audiobus:ddr_manager: 2, irqs toddr 26, frddr 29 [01/06, 09:43:53] [ 4.285912@2] loopback ff642000.audiobus:loopback@0: check whether to update loopback chipinfo [01/06, 09:43:53] [ 4.285971@2] datain_src:4, datain_chnum:4, datain_chumask:f [01/06, 09:43:53] [ 4.285973@2] datalb_src:1, datalb_chnum:2, datalb_chmask:3 [01/06, 09:43:53] [ 4.285975@2] datain_lane_mask:0x5, datalb_lane_mask:0x1 [01/06, 09:43:53] [ 4.285977@2] datalb_format: 1, chmask for lanes: 0x3 [01/06, 09:43:53] [ 4.286266@2] loopback_platform_probe, p_loopback->id:0 register soc platform [01/06, 09:43:53] [ 4.286677@2] audiolocker_platform_probe [01/06, 09:43:53] [ 4.287138@2] resample_platform_probe [01/06, 09:43:53] [ 4.287244@2] resample_clk_set, resample_pll:24575987, sclk:12287994, clk:12287994 [01/06, 09:43:53] [ 4.287248@2] audio_ddr_mngr: aml_set_resample(), toddr NULL [01/06, 09:43:53] [ 4.287251@2] resample id = 0, new resample = 0, resample_module = 4 [01/06, 09:43:53] [ 4.288373@1] Register vad [01/06, 09:43:53] [ 4.288936@1] asoc-aml-card auge_sound: aml_card_dai_link_of, dai-link idx:1, error getting codec dai name:(null) [01/06, 09:43:53] [ 4.288939@1] asoc-aml-card auge_sound: parse dai_link-1 fail [01/06, 09:43:53] [ 4.288943@1] asoc-aml-card auge_sound: aml_card_probe, aml_card_parse_of error -517 PROBE RETRY [01/06, 09:43:53] [ 4.288945@1] aml_card_probe error ret:-517 [01/06, 09:43:53] [ 4.289125@1] GACT probability NOT on [01/06, 09:43:53] [ 4.289138@1] Mirror/redirect action on [01/06, 09:43:53] [ 4.289146@1] u32 classifier [01/06, 09:43:53] [ 4.289148@1] Actions configured [01/06, 09:43:53] [ 4.289153@1] Netfilter messages via NETLINK v0.30. [01/06, 09:43:53] [ 4.289425@1] nf_conntrack version 0.5.0 (16384 buckets, 65536 max) [01/06, 09:43:53] [ 4.289581@1] ctnetlink v0.93: registering with nfnetlink. [01/06, 09:43:53] [ 4.289878@1] xt_time: kernel timezone is -0000 [01/06, 09:43:53] [ 4.289945@1] ipip: IPv4 and MPLS over IPv4 tunneling driver [01/06, 09:43:53] [ 4.290353@1] gre: GRE over IPv4 demultiplexor driver [01/06, 09:43:53] [ 4.290356@1] IPv4 over IPsec tunneling driver [01/06, 09:43:53] [ 4.292042@1] ip_tables: (C) 2000-2006 Netfilter Core Team [01/06, 09:43:53] [ 4.292224@1] arp_tables: arp_tables: (C) 2002 David S. Miller [01/06, 09:43:53] [ 4.296243@1] Initializing XFRM netlink socket [01/06, 09:43:53] [ 4.296275@1] IPsec XFRM device driver [01/06, 09:43:53] [ 4.296769@1] NET: Registered protocol family 10 [01/06, 09:43:53] [ 4.298089@0] mip6: Mobile IPv6 [01/06, 09:43:53] [ 4.298111@0] ip6_tables: (C) 2000-2006 Netfilter Core Team [01/06, 09:43:53] [ 4.300433@0] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [01/06, 09:43:53] [ 4.301765@0] NET: Registered protocol family 17 [01/06, 09:43:53] [ 4.301788@0] NET: Registered protocol family 15 [01/06, 09:43:53] [ 4.301823@0] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. [01/06, 09:43:53] [ 4.301933@1] Bluetooth: RFCOMM TTY layer initialized [01/06, 09:43:53] [ 4.301944@1] Bluetooth: RFCOMM socket layer initialized [01/06, 09:43:53] [ 4.301972@1] Bluetooth: RFCOMM ver 1.11 [01/06, 09:43:53] [ 4.301981@1] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [01/06, 09:43:53] [ 4.301983@1] Bluetooth: BNEP filters: protocol multicast [01/06, 09:43:53] [ 4.301991@1] Bluetooth: BNEP socket layer initialized [01/06, 09:43:53] [ 4.301993@1] Bluetooth: HIDP (Human Interface Emulation) ver 1.2 [01/06, 09:43:53] [ 4.302000@1] Bluetooth: HIDP socket layer initialized [01/06, 09:43:53] [ 4.302025@1] l2tp_core: L2TP core driver, V2.0 [01/06, 09:43:53] [ 4.302035@1] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [01/06, 09:43:53] [ 4.302036@1] l2tp_ip: L2TP IP encapsulation support (L2TPv3) [01/06, 09:43:53] [ 4.302065@1] l2tp_netlink: L2TP netlink interface [01/06, 09:43:53] [ 4.302101@1] l2tp_eth: L2TP ethernet pseudowire support (L2TPv3) [01/06, 09:43:53] [ 4.302118@1] l2tp_debugfs: L2TP debugfs support [01/06, 09:43:53] [ 4.302120@1] l2tp_ip6: L2TP IP encapsulation support for IPv6 (L2TPv3) [01/06, 09:43:53] [ 4.302294@1] NET: Registered protocol family 35 [01/06, 09:43:53] [ 4.302457@1] Key type dns_resolver registered [01/06, 09:43:53] [ 4.305465@1] Registered swp emulation handler [01/06, 09:43:53] [ 4.311052@1] Registered cp15_barrier emulation handler [01/06, 09:43:53] [ 4.311060@1] Registered setend emulation handler [01/06, 09:43:53] [ 4.311100@1] disable EAS feature [01/06, 09:43:53] [ 4.322005@2] registered taskstats version 1 [01/06, 09:43:53] [ 4.332724@2] asoc-aml-card auge_sound: aml_card_dai_link_of, dai-link idx:1, error getting codec dai name:(null) [01/06, 09:43:53] [ 4.332728@2] asoc-aml-card auge_sound: parse dai_link-1 fail [01/06, 09:43:53] [ 4.332731@2] asoc-aml-card auge_sound: aml_card_probe, aml_card_parse_of error -517 PROBE RETRY [01/06, 09:43:53] [ 4.332734@2] aml_card_probe error ret:-517 [01/06, 09:43:53] [ 4.333600@1] dwc3 ff500000.dwc3: Configuration mismatch. dr_mode forced to host [01/06, 09:43:53] [ 4.336423@1] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [01/06, 09:43:53] [ 4.336440@1] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1 [01/06, 09:43:53] [ 4.336701@1] xhci-hcd xhci-hcd.0.auto: hcc params 0x0228fe6c hci version 0x110 quirks 0x20010010 [01/06, 09:43:53] [ 4.336752@1] xhci-hcd xhci-hcd.0.auto: irq 15, io mem 0xff500000 [01/06, 09:43:53] [ 4.337492@2] hub 1-0:1.0: USB hub found [01/06, 09:43:53] [ 4.337517@2] hub 1-0:1.0: 2 ports detected [01/06, 09:43:53] [ 4.338638@2] asoc-aml-card auge_sound: aml_card_dai_link_of, dai-link idx:1, error getting codec dai name:(null) [01/06, 09:43:53] [ 4.338641@2] asoc-aml-card auge_sound: parse dai_link-1 fail [01/06, 09:43:53] [ 4.338645@2] asoc-aml-card auge_sound: aml_card_probe, aml_card_parse_of error -517 PROBE RETRY [01/06, 09:43:53] [ 4.338647@2] aml_card_probe error ret:-517 [01/06, 09:43:53] [ 4.339366@2] asoc-aml-card auge_sound: aml_card_dai_link_of, dai-link idx:1, error getting codec dai name:(null) [01/06, 09:43:53] [ 4.339369@2] asoc-aml-card auge_sound: parse dai_link-1 fail [01/06, 09:43:53] [ 4.339372@2] asoc-aml-card auge_sound: aml_card_probe, aml_card_parse_of error -517 PROBE RETRY [01/06, 09:43:54] [ 4.339374@2] aml_card_probe error ret:-517 [01/06, 09:43:54] [ 4.339467@2] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [01/06, 09:43:54] [ 4.339478@2] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2 [01/06, 09:43:54] [ 4.339558@2] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [01/06, 09:43:54] [ 4.340214@0] hub 2-0:1.0: USB hub found [01/06, 09:43:54] [ 4.340238@0] hub 2-0:1.0: 1 port detected [01/06, 09:43:54] [ 4.341282@0] asoc-aml-card auge_sound: aml_card_dai_link_of, dai-link idx:1, error getting codec dai name:(null) [01/06, 09:43:54] [ 4.341285@0] asoc-aml-card auge_sound: parse dai_link-1 fail [01/06, 09:43:54] [ 4.341288@0] asoc-aml-card auge_sound: aml_card_probe, aml_card_parse_of error -517 PROBE RETRY [01/06, 09:43:54] [ 4.341290@0] aml_card_probe error ret:-517 [01/06, 09:43:54] [ 4.342024@2] asoc-aml-card auge_sound: aml_card_dai_link_of, dai-link idx:1, error getting codec dai name:(null) [01/06, 09:43:54] [ 4.342027@2] asoc-aml-card auge_sound: parse dai_link-1 fail [01/06, 09:43:54] [ 4.342030@2] asoc-aml-card auge_sound: aml_card_probe, aml_card_parse_of error -517 PROBE RETRY [01/06, 09:43:54] [ 4.342032@2] aml_card_probe error ret:-517 [01/06, 09:43:54] [ 4.342703@2] asoc-aml-card auge_sound: aml_card_dai_link_of, dai-link idx:1, error getting codec dai name:(null) [01/06, 09:43:54] [ 4.342706@2] asoc-aml-card auge_sound: parse dai_link-1 fail [01/06, 09:43:54] [ 4.342709@2] asoc-aml-card auge_sound: aml_card_probe, aml_card_parse_of error -517 PROBE RETRY [01/06, 09:43:54] [ 4.342711@2] aml_card_probe error ret:-517 [01/06, 09:43:54] [ 4.346725@0] aml_vrtc rtc: setting system clock to 2021-01-06 00:43:50 UTC (1609893830) [01/06, 09:43:54] [ 4.347256@0] dwc_otg ff400000.dwc2_a: dwc_otg_driver_probe(0000000032cbba3b) [01/06, 09:43:54] [ 4.347298@0] dwc_otg: usb0: type: 0 speed: 0, config: 0, dma: 0, id: 0, phy: ffe09000, ctrl: 0 [01/06, 09:43:54] [ 4.347309@0] dwc_otg ff400000.dwc2_a: base=0xffffff8008b00000 [01/06, 09:43:54] [ 4.347312@0] dwc_otg ff400000.dwc2_a: dwc_otg_device=0x0000000022e50dbd [01/06, 09:43:54] [ 4.366169@0] meson-aml-mmc ffe07000.emmc: divider requested rate 200000000 != actual rate 199999997: ret=0 [01/06, 09:43:54] [ 4.366173@0] meson-mmc: actual_clock :199999997, HHI_nand: 0x80 [01/06, 09:43:54] [ 4.366175@0] meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000345 [01/06, 09:43:54] [ 4.366181@0] meson-mmc: Data 1 aligned delay is 0 [01/06, 09:43:54] [ 4.366185@0] meson-mmc: emmc: clk 199999997 tuning start [01/06, 09:43:54] [ 4.369978@0] meson-mmc: emmc: adj_win: < 0 1 3 4 > [01/06, 09:43:54] [ 4.369983@0] meson-mmc: step:4, delay1:0x4104104, delay2:0x4004104 [01/06, 09:43:54] [ 4.373740@0] meson-mmc: emmc: adj_win: < 0 1 3 4 > [01/06, 09:43:54] [ 4.373744@0] meson-mmc: step:8, delay1:0x8208208, delay2:0x8008208 [01/06, 09:43:54] [ 4.376997@0] meson-mmc: emmc: adj_win: < 0 1 4 > [01/06, 09:43:54] [ 4.377001@0] meson-mmc: left:3, right:0, mid:4, size:4 [01/06, 09:43:54] [ 4.377004@0] meson-mmc: step:0, delay1:0x0, delay2:0x0 [01/06, 09:43:54] [ 4.377007@0] meson-mmc: emmc: sd_emmc_regs->gclock=0x10000345,sd_emmc_regs->gadjust=0x2000 [01/06, 09:43:54] [ 4.377009@0] meson-mmc: delay1:0x0, delay2:0x0 [01/06, 09:43:54] [ 4.377107@0] emmc: new HS200 MMC card at address 0001 [01/06, 09:43:54] [ 4.377672@0] emmc: clock 199999997, 8-bit-bus-width [01/06, 09:43:54] [ 4.377672@0] [01/06, 09:43:54] [ 4.377672@0] mmcblk0: emmc:0001 8GTF4R 7.28 GiB [01/06, 09:43:54] [ 4.377830@0] mmcblk0boot0: emmc:0001 8GTF4R partition 1 4.00 MiB [01/06, 09:43:54] [ 4.377998@0] mmcblk0boot1: emmc:0001 8GTF4R partition 2 4.00 MiB [01/06, 09:43:54] [ 4.378155@0] mmcblk0rpmb: emmc:0001 8GTF4R partition 3 512 KiB [01/06, 09:43:54] [ 4.379132@0] meson-mmc: Enter aml_emmc_partition_ops [01/06, 09:43:54] [ 4.379674@0] meson-mmc: [mmc_read_partition_tbl] mmc read partition OK! [01/06, 09:43:54] [ 4.379675@0] meson-mmc: add_emmc_partition [01/06, 09:43:54] [ 4.379831@0] meson-mmc: [mmcblk0p01] bootloader offset 0x000000000000, size 0x000000400000 [01/06, 09:43:54] [ 4.379951@0] meson-mmc: [mmcblk0p02] reserved offset 0x000002400000, size 0x000004000000 [01/06, 09:43:54] [ 4.380065@0] meson-mmc: [mmcblk0p03] cache offset 0x000006c00000, size 0x000043800000 [01/06, 09:43:54] [ 4.380185@0] meson-mmc: [mmcblk0p04] env offset 0x00004ac00000, size 0x000000800000 [01/06, 09:43:54] [ 4.380303@0] meson-mmc: [mmcblk0p05] logo offset 0x00004bc00000, size 0x000000800000 [01/06, 09:43:54] [ 4.380418@0] meson-mmc: [mmcblk0p06] recovery offset 0x00004cc00000, size 0x000001800000 [01/06, 09:43:54] [ 4.380543@0] meson-mmc: [mmcblk0p07] misc offset 0x00004ec00000, size 0x000000800000 [01/06, 09:43:54] [ 4.380655@0] meson-mmc: [mmcblk0p08] dtbo offset 0x00004fc00000, size 0x000000800000 [01/06, 09:43:54] [ 4.380775@0] meson-mmc: [mmcblk0p09] cri_data offset 0x000050c00000, size 0x000000800000 [01/06, 09:43:54] [ 4.380895@0] meson-mmc: [mmcblk0p10] param offset 0x000051c00000, size 0x000001000000 [01/06, 09:43:54] [ 4.381007@0] meson-mmc: [mmcblk0p11] boot offset 0x000053400000, size 0x000001000000 [01/06, 09:43:54] [ 4.381125@0] meson-mmc: [mmcblk0p12] rsv offset 0x000054c00000, size 0x000001000000 [01/06, 09:43:54] [ 4.381307@0] meson-mmc: [mmcblk0p13] metadata offset 0x000056400000, size 0x000001000000 [01/06, 09:43:54] [ 4.381423@0] meson-mmc: [mmcblk0p14] vbmeta offset 0x000057c00000, size 0x000000200000 [01/06, 09:43:54] [ 4.381542@0] meson-mmc: [mmcblk0p15] tee offset 0x000058600000, size 0x000002000000 [01/06, 09:43:54] [ 4.381659@0] meson-mmc: [mmcblk0p16] factory offset 0x00005ae00000, size 0x000000800000 [01/06, 09:43:54] [ 4.381787@0] meson-mmc: [mmcblk0p17] super offset 0x00005be00000, size 0x000065200000 [01/06, 09:43:54] [ 4.381906@0] meson-mmc: [mmcblk0p18] factory_setting offset 0x0000c1800000, size 0x000001000000 [01/06, 09:43:54] [ 4.382021@0] meson-mmc: [mmcblk0p19] btv_home offset 0x0000c3000000, size 0x000006400000 [01/06, 09:43:54] [ 4.382146@0] meson-mmc: [mmcblk0p20] data offset 0x0000c9c00000, size 0x000108400000 [01/06, 09:43:54] [ 4.382168@0] card key: card_blk_probe. [01/06, 09:43:54] [ 4.382173@0] emmc_key_init:183 emmc key lba_start:0x12020,lba_end:0x12220 [01/06, 09:43:54] [ 4.382176@0] emmc key: emmc_key_init:205 ok. [01/06, 09:43:54] [ 4.384376@0] meson-mmc: amlmmc_dtb_init: register dtb chardev [01/06, 09:43:54] [ 4.384376@0] meson-mmc: calc df3edcc2, store df3edcc2 [01/06, 09:43:54] [ 4.386217@0] meson-mmc: calc df3edcc2, store df3edcc2 [01/06, 09:43:54] [ 4.386219@0] meson-mmc: total valid 2 [01/06, 09:43:54] [ 4.386493@0] meson-mmc: amlmmc_dtb_init: register dtb chardev OK [01/06, 09:43:54] [ 4.386493@0] meson-mmc: Exit aml_emmc_partition_ops OK. [01/06, 09:43:54] [ 4.388420@0] asoc-aml-card auge_sound: aml_card_dai_link_of, dai-link idx:1, error getting codec dai name:(null) [01/06, 09:43:54] [ 4.388423@0] asoc-aml-card auge_sound: parse dai_link-1 fail [01/06, 09:43:54] [ 4.388427@0] asoc-aml-card auge_sound: aml_card_probe, aml_card_parse_of error -517 PROBE RETRY [01/06, 09:43:54] [ 4.388429@0] aml_card_probe error ret:-517 [01/06, 09:43:54] [ 4.448818@0] dwc_otg: Core Release: 3.30a [01/06, 09:43:54] [ 4.448823@0] dwc_otg: Setting default values for core params [01/06, 09:43:54] [ 4.448838@0] dwc_otg: curmode: 0, host_only: 0 [01/06, 09:43:54] [ 4.448868@0] dwc_otg ff400000.dwc2_a: DMA config: BURST_DEFAULT [01/06, 09:43:54] [ 4.461180@0] dwc_otg: Using Buffer DMA mode [01/06, 09:43:54] [ 4.461183@0] dwc_otg: OTG VER PARAM: 1, OTG VER FLAG: 1 [01/06, 09:43:54] [ 4.461186@0] dwc_otg: Working on port type = SLAVE [01/06, 09:43:54] [ 4.461191@0] dwc_otg: Dedicated Tx FIFOs mode [01/06, 09:43:54] [ 4.463188@0] asoc-aml-card auge_sound: aml_card_dai_link_of, dai-link idx:1, error getting codec dai name:(null) [01/06, 09:43:54] [ 4.463191@0] asoc-aml-card auge_sound: parse dai_link-1 fail [01/06, 09:43:54] [ 4.463195@0] asoc-aml-card auge_sound: aml_card_probe, aml_card_parse_of error -517 PROBE RETRY [01/06, 09:43:54] [ 4.463197@0] aml_card_probe error ret:-517 [01/06, 09:43:54] [ 4.465178@0] meson_cdev probe [01/06, 09:43:54] [ 4.465191@0] thermal: read gpupp failed [01/06, 09:43:54] [ 4.465324@0] meson_cdev index: 0 [01/06, 09:43:54] [ 4.465492@0] thermal: read gpupp failed [01/06, 09:43:54] [ 4.465550@0] meson_cdev index: 1 [01/06, 09:43:54] [ 4.465556@0] cpucore_cooling_register, max_cpu_core_num:4 [01/06, 09:43:54] [ 4.465672@0] meson_cdev index: 2 [01/06, 09:43:54] [ 4.465677@0] thermal: read gpupp failed [01/06, 09:43:54] [ 4.465695@0] meson_cdev index: 3 [01/06, 09:43:54] [ 4.465735@0] find tzd id: 0 [01/06, 09:43:54] [ 4.465879@0] find tzd id: 0 [01/06, 09:43:54] [ 4.465917@0] meson_cdev probe done [01/06, 09:43:54] [ 4.466739@0] asoc-aml-card auge_sound: aml_card_dai_link_of, dai-link idx:1, error getting codec dai name:(null) [01/06, 09:43:54] [ 4.466743@0] asoc-aml-card auge_sound: parse dai_link-1 fail [01/06, 09:43:54] [ 4.466746@0] asoc-aml-card auge_sound: aml_card_probe, aml_card_parse_of error -517 PROBE RETRY [01/06, 09:43:54] [ 4.466749@0] aml_card_probe error ret:-517 [01/06, 09:43:54] [ 4.467045@0] gxbb_pm: enter meson_pm_probe! [01/06, 09:43:54] [ 4.467062@0] no vddio3v3_en pin [01/06, 09:43:54] [ 4.467062@0] pm-meson aml_pm: Can't get switch_clk81 [01/06, 09:43:54] [ 4.467097@0] gxbb_pm: meson_pm_probe done [01/06, 09:43:54] [ 4.467792@0] asoc-aml-card auge_sound: aml_card_dai_link_of, dai-link idx:1, error getting codec dai name:(null) [01/06, 09:43:54] [ 4.467796@0] asoc-aml-card auge_sound: parse dai_link-1 fail [01/06, 09:43:54] [ 4.467799@0] asoc-aml-card auge_sound: aml_card_probe, aml_card_parse_of error -517 PROBE RETRY [01/06, 09:43:54] [ 4.467801@0] aml_card_probe error ret:-517 [01/06, 09:43:54] [ 4.468393@8 6.769482@0] meson_uart ff803000.serial: ttyS0 use xtal(24M) 24000000 change 115200 to 115200 [01/06, 09:43:54] [ 6.774369@0] Freeing unused kernel memory: 5440K [01/06, 09:43:54] [ 6.808192@0] init: init first stage started! [01/06, 09:43:54] [ 6.808346@0] init: First stage mount skipped (recovery mode) [01/06, 09:43:54] [ 6.815472@0] init: Using Android DT directory /proc/device-tree/firmware/android/ [01/06, 09:43:54] [ 6.860024@2] init: [libfs_avb]Returning avb_handle with status: HashtreeDisabled [01/06, 09:43:54] [ 6.890376@2] init: Loading SELinux policy [01/06, 09:43:54] [ 6.895491@2] SELinux: 8192 avtab hash slots, 27153 rules. [01/06, 09:43:54] [ 6.910155@2] SELinux: 8192 avtab hash slots, 27153 rules. [01/06, 09:43:54] [ 6.910191@2] SELinux: 1 users, 2 roles, 1657 types, 0 bools, 1 sens, 1024 cats [01/06, 09:43:54] [ 6.917377@2] SELinux: 97 classes, 27153 rules [01/06, 09:43:54] [ 6.924813@2] SELinux: Completing initialization. [01/06, 09:43:54] [ 6.926684@2] SELinux: Setting up existing superblocks. [01/06, 09:43:54] [ 6.989324@0] lcd vlock_en=1, vlock_mode=0x4 [01/06, 09:43:54] [ 7.041645@2] audit: type=1403 audit(1609893833.196:2): policy loaded auid=4294967295 ses=4294967295 [01/06, 09:43:54] [ 7.045386@2] selinux: SELinux: Loaded policy from /sepolicy [01/06, 09:43:54] [ 7.045386@2] [01/06, 09:43:54] [ 7.052961@2] audit: type=1404 audit(1609893833.208:3): enforcing=1 old_enforcing=0 auid=4294967295 ses=4294967295 [01/06, 09:43:54] [ 7.089867@2] selinux: SELinux: Skipping /product_file_contexts: empty file [01/06, 09:43:54] [ 7.089867@2] [01/06, 09:43:54] [ 7.093115@2] selinux: SELinux: Skipping /odm_file_contexts: empty file [01/06, 09:43:54] [ 7.093115@2] [01/06, 09:43:54] [ 7.101543@2] selinux: SELinux: Loaded file_contexts [01/06, 09:43:54] [ 7.101543@2] [01/06, 09:43:54] [ 7.138408@2] init: init second stage started! [01/06, 09:43:54] [ 7.164659@2] init: Using Android DT directory /proc/device-tree/firmware/android/ [01/06, 09:43:54] [ 7.172413@2] selinux: SELinux: Skipping /product_file_contexts: empty file [01/06, 09:43:54] [ 7.172413@2] [01/06, 09:43:54] [ 7.175754@2] selinux: SELinux: Skipping /odm_file_contexts: empty file [01/06, 09:43:54] [ 7.175754@2] [01/06, 09:43:54] [ 7.184075@2] selinux: SELinux: Loaded file_contexts [01/06, 09:43:54] [ 7.184075@2] [01/06, 09:43:54] [ 7.190590@2] init: Running restorecon... [01/06, 09:43:54] [ 7.204750@2] init: Couldn't load property file '/system/etc/prop.default': open() failed: No such file or directory: No such file or directory [01/06, 09:43:54] [ 7.213134@2] init: Overriding previous 'ro.' property 'persist.sys.usb.config':'adb' with new value 'mtp' [01/06, 09:43:54] [ 7.224753@2] init: Couldn't load property file '/system/build.prop': open() failed: No such file or directory: No such file or directory [01/06, 09:43:54] [ 7.233958@2] init: Couldn't load property file '/vendor/default.prop': open() failed: No such file or directory: No such file or directory [01/06, 09:43:55] [ 7.301157@0] ueventd: ueventd started! [01/06, 09:43:55] [ 7.305705@0] selinux: SELinux: Skipping /product_file_contexts: empty file [01/06, 09:43:55] [ 7.305705@0] [01/06, 09:43:55] [ 7.309021@0] selinux: SELinux: Skipping /odm_file_contexts: empty file [01/06, 09:43:55] [ 7.309021@0] [01/06, 09:43:55] [ 7.317392@0] selinux: SELinux: Loaded file_contexts [01/06, 09:43:55] [ 7.317392@0] [01/06, 09:43:55] [ 7.323930@0] ueventd: Parsing file /ueventd.rc... [01/06, 09:43:55] [ 7.329022@0] ueventd: Parsing file /vendor/ueventd.rc... [01/06, 09:43:55] [ 7.334034@0] ueventd: Unable to read config file '/vendor/ueventd.rc': open() failed: No such file or directory [01/06, 09:43:55] [ 7.344155@0] ueventd: Parsing file /odm/ueventd.rc... [01/06, 09:43:55] [ 7.349283@0] ueventd: Unable to read config file '/odm/ueventd.rc': open() failed: No such file or directory [01/06, 09:43:55] [ 7.359146@0] ueventd: Parsing file /ueventd.amlogic.rc... [01/06, 09:43:57] [ 9.609727@0] remote: loading out-of-tree module taints kernel. [01/06, 09:43:57] [ 9.611691@0] meson-remote: Driver init [01/06, 09:43:57] [ 9.614136@0] meson-remote: remote_probe [01/06, 09:43:57] [ 9.617754@0] meson-remote ff808040.rc: protocol = 0x1 [01/06, 09:43:57] [ 9.622853@0] meson-remote ff808040.rc: led_blink = 1 [01/06, 09:43:57] [ 9.627870@0] meson-remote ff808040.rc: led_blink_frq = 100 [01/06, 09:43:57] [ 9.633544@0] meson-remote ff808040.rc: platform_data irq =31 [01/06, 09:43:57] [ 9.639271@0] meson-remote ff808040.rc: custom_number = 3 [01/06, 09:43:57] [ 9.644611@0] meson-remote ff808040.rc: ptable->map_size = 235 [01/06, 09:43:57] [ 9.650405@0] meson-remote ff808040.rc: ptable->custom_name = amlogic-remote-1 [01/06, 09:43:57] [ 9.657597@0] meson-remote ff808040.rc: ptable->custom_code = 0x7f80 [01/06, 09:43:57] [ 9.663928@0] meson-remote ff808040.rc: ptable->release_delay = 80 [01/06, 09:43:57] [ 9.670221@0] meson-remote ff808040.rc: ptable->map_size = 235 [01/06, 09:43:57] [ 9.675886@0] meson-remote ff808040.rc: ptable->custom_name = amlogic-remote-2 [01/06, 09:43:57] [ 9.683081@0] meson-remote ff808040.rc: ptable->custom_code = 0xa0f5 [01/06, 09:43:57] [ 9.689404@0] meson-remote ff808040.rc: ptable->release_delay = 80 [01/06, 09:43:57] [ 9.695697@0] meson-remote ff808040.rc: ptable->map_size = 17 [01/06, 09:43:57] [ 9.701276@0] meson-remote ff808040.rc: ptable->custom_name = amlogic-remote-3 [01/06, 09:43:57] [ 9.708468@0] meson-remote ff808040.rc: ptable->custom_code = 0xbd02 [01/06, 09:43:57] [ 9.714795@0] meson-remote ff808040.rc: ptable->release_delay = 80 [01/06, 09:43:57] [ 9.720953@0] meson-remote ff808040.rc: default protocol = 0x1 and id = 0 [01/06, 09:43:57] [ 9.727709@0] meson-remote ff808040.rc: reg=0x0, val=0x1f40190 [01/06, 09:43:57] [ 9.733518@0] meson-remote ff808040.rc: reg=0x4, val=0x12c00c8 [01/06, 09:43:57] [ 9.739322@0] meson-remote ff808040.rc: reg=0x8, val=0x960050 [01/06, 09:43:57] [ 9.745042@0] meson-remote ff808040.rc: reg=0xc, val=0x480028 [01/06, 09:43:57] [ 9.750762@0] meson-remote ff808040.rc: reg=0x10, val=0x70fa0013 [01/06, 09:43:57] [ 9.756742@0] meson-remote ff808040.rc: reg=0x18, val=0x8616800 [01/06, 09:43:57] [ 9.762635@0] meson-remote ff808040.rc: reg=0x1c, val=0x9f00 [01/06, 09:43:57] [ 9.768268@0] meson-remote ff808040.rc: reg=0x20, val=0x0 [01/06, 09:43:57] [ 9.773641@0] meson-remote ff808040.rc: reg=0x24, val=0x0 [01/06, 09:43:57] [ 9.779015@0] meson-remote ff808040.rc: reg=0x28, val=0x0 [01/06, 09:43:57] [ 9.784987@3] input: aml_keypad as /devices/platform/ff808040.rc/input/input2 [01/06, 09:43:57] [ 9.792697@3] asoc-aml-card auge_sound: aml_card_dai_link_of, dai-link idx:1, error getting codec dai name:(null) [01/06, 09:43:57] [ 9.801774@3] asoc-aml-card auge_sound: parse dai_link-1 fail [01/06, 09:43:57] [ 9.807483@3] asoc-aml-card auge_sound: aml_card_probe, aml_card_parse_of error -517 PROBE RETRY [01/06, 09:43:57] [ 9.816205@3] aml_card_probe error ret:-517 [01/06, 09:43:57] [ 9.877441@0] meson6-dwmac ff3f0000.ethernet eth0: fail to init PTP. [01/06, 09:43:57] [ 9.878578@0] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [01/06, 09:43:57] # [ 9.906962@1] healthd: BatteryTemperaturePath not found [01/06, 09:43:57] [ 9.906999@1] healthd: BatteryCycleCountPath not found [01/06, 09:43:57] [ 9.911080@0] file system registered [01/06, 09:43:57] [ 9.912896@2] meson-remote ff808040.rc: default protocol = 0x1 and id = 0 [01/06, 09:43:57] [ 9.912900@2] meson-remote ff808040.rc: reg=0x0, val=0x1f40190 [01/06, 09:43:57] [ 9.912903@2] meson-remote ff808040.rc: reg=0x4, val=0x12c00c8 [01/06, 09:43:57] [ 9.912905@2] meson-remote ff808040.rc: reg=0x8, val=0x960050 [01/06, 09:43:57] [ 9.912908@2] meson-remote ff808040.rc: reg=0xc, val=0x480028 [01/06, 09:43:57] [ 9.912910@2] meson-remote ff808040.rc: reg=0x10, val=0x70fa0013 [01/06, 09:43:57] [ 9.912912@2] meson-remote ff808040.rc: reg=0x18, val=0x8616800 [01/06, 09:43:57] [ 9.912915@2] meson-remote ff808040.rc: reg=0x1c, val=0x9f00 [01/06, 09:43:57] [ 9.912918@2] meson-remote ff808040.rc: reg=0x20, val=0x0 [01/06, 09:43:57] [ 9.912920@2] meson-remote ff808040.rc: reg=0x24, val=0x0 [01/06, 09:43:57] [ 9.912923@2] meson-remote ff808040.rc: reg=0x28, val=0x0 [01/06, 09:43:57] [ 9.920871@0] meson-remote ff808040.rc: default protocol = 0x1 and id = 0 [01/06, 09:43:57] [ 9.920875@0] meson-remote ff808040.rc: reg=0x0, val=0x1f40190 [01/06, 09:43:57] [ 9.920878@0] meson-remote ff808040.rc: reg=0x4, val=0x12c00c8 [01/06, 09:43:57] [ 9.920880@0] meson-remote ff808040.rc: reg=0x8, val=0x960050 [01/06, 09:43:57] [ 9.920883@0] meson-remote ff808040.rc: reg=0xc, val=0x480028 [01/06, 09:43:57] [ 9.920885@0] meson-remote ff808040.rc: reg=0x10, val=0x70fa0013 [01/06, 09:43:57] [ 9.920887@0] meson-remote ff808040.rc: reg=0x18, val=0x8616800 [01/06, 09:43:57] [ 9.920890@0] meson-remote ff808040.rc: reg=0x1c, val=0x9f00 [01/06, 09:43:57] [ 9.920893@0] meson-remote ff808040.rc: reg=0x20, val=0x0 [01/06, 09:43:57] [ 9.920895@0] meson-remote ff808040.rc: reg=0x24, val=0x0 [01/06, 09:43:57] [ 9.920897@0] meson-remote ff808040.rc: reg=0x28, val=0x0 [01/06, 09:43:57] [ 9.923281@2] audit: type=1400 audit(1609893836.076:4): avc: denied { getattr } for pid=3134 comm="systemcontrol_s" path="/dev/kmsg" dev="tmpfs" ino=11379 scontext=u:r:recovery:s0 tcontext=u:object_r:kmsg_device:s0 tclass=chr_file permissive=0 [01/06, 09:43:57] [ 9.931652@2] systemcontrol: [ubootenv] using /dev/block/env with size(65536) (65532) [01/06, 09:43:57] [ 9.931652@2] assign_ffs_buffer FFS_BUFFER_MAX=100!!! [01/06, 09:43:57] [ 9.932555@0] meson-remote ff808040.rc: default protocol = 0x1 and id = 0 [01/06, 09:43:57] [ 9.932560@0] meson-remote ff808040.rc: reg=0x0, val=0x1f40190 [01/06, 09:43:57] [ 9.932562@0] meson-remote ff808040.rc: reg=0x4, val=0x12c00c8 [01/06, 09:43:57] [ 9.932564@0] meson-remote ff808040.rc: reg=0x8, val=0x960050 [01/06, 09:43:57] [ 9.932567@0] meson-remote ff808040.rc: reg=0xc, val=0x480028 [01/06, 09:43:57] [ 9.932569@0] meson-remote ff808040.rc: reg=0x10, val=0x70fa0013 [01/06, 09:43:57] [ 9.932572@0] meson-remote ff808040.rc: reg=0x18, val=0x8616800 [01/06, 09:43:57] [ 9.932574@0] meson-remote ff808040.rc: reg=0x1c, val=0x9f00 [01/06, 09:43:57] [ 9.932576@0] meson-remote ff808040.rc: reg=0x20, val=0x0 [01/06, 09:43:57] [ 9.932579@0] meson-remote ff808040.rc: reg=0x24, val=0x0 [01/06, 09:43:57] [ 9.932581@0] meson-remote ff808040.rc: reg=0x28, val=0x0 [01/06, 09:43:57] [ 9.933009@2] assign_ffs_buffer FFS_BUFFER_MAX=100!!! [01/06, 09:43:57] [ 9.934334@0] systemcontrol: [ubootenv] set property count: 0 [01/06, 09:43:57] [ 9.937634@0] systemcontrol: display mode config path: /sbin/mesondisplay.cfgsystemcontrol: Error opening file '/vendor/etc/filteredid.cfg', No such file or directory.systemcontrol: Error -2 opening display config file /vendor/etc/filteredid.cfg.systemcontrol: display mode init type: 2 [0:none 1:tablet 2:mbox 3:tv], soc type:g12a, default UI:1080psystemcontrol: failed to set system property vendor.system.support.dolbyvision [01/06, 09:43:57] [ 9.938554@0] systemcontrol: getBootEnv key:ubootenv.var.hdmimode value:1080p60hzsystemcontrol: getBootEnv key:ubootenv.var.is.bestmode value:(null)systemcontrol: the resolveResolution mode [1080p30hz] is not valid [01/06, 09:43:57] [ 9.939011@0] hdmitx: system: set hdcp_pwr 1 [01/06, 09:43:57] [ 9.962799@3] recovery: [libfs_mgr]superblock s_max_mnt_count:65535,/dev/block/cache [01/06, 09:43:57] [ 10.042286@2] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: [01/06, 09:43:57] [ 10.042550@2] recovery: [libfs_mgr]__mount(source=/dev/block/cache,target=/cache,type=ext4)=0: Success [01/06, 09:43:57] [ 10.076707@2] EXT4-fs (mmcblk0p10): mounted filesystem with ordered data mode. Opts: [01/06, 09:43:58] [ 10.129498@2] recovery: Got 2 arguments from /cache/recovery/command [01/06, 09:43:58] [ 10.219433@2] EXT4-fs (mmcblk0p13): mounted filesystem with ordered data mode. Opts: [01/06, 09:43:58] [ 10.259203@3] audit: type=1400 audit(1609893836.412:5): avc: denied { read } for pid=3129 comm="recovery" name="u:object_r:persistent_properties_ready_prop:s0" dev="tmpfs" ino=13164 scontext=u:r:recovery:s0 tcontext=u:object_r:persistent_properties_ready_prop:s0 tclass=file permissive=0 [01/06, 09:43:58] [ 10.259206@1] healthd: battery l=42 v=4 t=42.4 h=2 st=2 c=1 fc=10000000 chg=u [01/06, 09:43:58] [ 10.285359@2] EXT4-fs (mmcblk0p15): mounted filesystem with ordered data mode. Opts: [01/06, 09:43:58] [ 10.298873@3] recovery: [libfs_mgr]teardown: Device or resource busy [01/06, 09:43:58] [ 10.299731@0] EXT4-fs (mmcblk0p19): recovery complete [01/06, 09:43:58] [ 10.300167@0] EXT4-fs (mmcblk0p19): mounted filesystem with ordered data mode. Opts: [01/06, 09:43:58] [ 10.304146@0] EXT4-fs (mmcblk0p20): mounted filesystem with ordered data mode. Opts: [01/06, 09:43:58] [ 10.327508@3] audit: type=1400 audit(1609893836.480:6): avc: denied { search } for pid=3129 comm="recovery" name="/" dev="mmcblk0p13" ino=2 scontext=u:r:recovery:s0 tcontext=u:object_r:metadata_file:s0 tclass=dir permissive=0 [01/06, 09:43:58] [ 10.337744@0] hdmitx: system: store_phy 0 [01/06, 09:43:58] [ 10.337981@0] hdmitx: store_avmute 2 [01/06, 09:43:58] [ 10.337986@0] hdmitx: hw: avmute set to 2 [01/06, 09:43:58] [ 10.352654@2] hdmitx: system: restore hdcp_pwr 0 [01/06, 09:43:58] [ 10.352724@2] amhdmitx amhdmitx0: hdmitx20: stop14 [01/06, 09:43:58] [ 10.354807@2] amhdmitx amhdmitx0: hdmitx20: stop22 [01/06, 09:43:58] [ 10.357465@2] hdmitx: system: sname = 1080p60hz [01/06, 09:43:58] [ 10.357467@2] hdmitx: system: char_clk = 148500 [01/06, 09:43:58] [ 10.357469@2] hdmitx: system: cd = 4 [01/06, 09:43:58] [ 10.357470@2] hdmitx: system: cs = 2 [01/06, 09:43:58] [ 10.357475@2] hdmitx: RX tmds clk: 150 Calc clk: 148 [01/06, 09:43:58] [ 10.357881@2] audit: type=1400 audit(1609893836.512:7): avc: denied { read } for pid=3134 comm="systemcontrol_s" name="u:object_r:netflix_prop:s0" dev="tmpfs" ino=13157 scontext=u:r:recovery:s0 tcontext=u:object_r:netflix_prop:s0 tclass=file permissive=0 [01/06, 09:43:58] [ 10.358144@2] audit: type=1400 audit(1609893836.512:8): avc: denied { read } for pid=3134 comm="systemcontrol_s" name="u:object_r:netflix_prop:s0" dev="tmpfs" ino=13157 scontext=u:r:recovery:s0 tcontext=u:object_r:netflix_prop:s0 tclass=file permissive=0 [01/06, 09:43:58] [ 10.359526@2] audit: type=1400 audit(1609893836.512:9): avc: denied { read } for pid=3134 comm="systemcontrol_s" name="u:object_r:netflix_prop:s0" dev="tmpfs" ino=13157 scontext=u:r:recovery:s0 tcontext=u:object_r:netflix_prop:s0 tclass=file permissive=0 [01/06, 09:43:58] [ 10.467689@3] recovery: Failed to read max brightness: No such file or directory [01/06, 09:43:58] [ 10.469500@3] recovery: Screensaver disabled [01/06, 09:43:58] [ 10.476245@3] fb: osd_open, 1507, fb_index=0,fb_rmem_size=26738688 [01/06, 09:43:58] [ 10.479983@3] fb: malloc_osd_memory, cma:00000000cb01d7d0 [01/06, 09:43:58] [ 10.485242@3] fb: malloc_osd_memory, 1201, base:0x00000000bf800000, size:8388608 [01/06, 09:43:58] [ 10.492601@3] fb: use ion buffer for fb memory, fb_index=0 [01/06, 09:43:58] [ 10.498059@3] fb: OSD0 as afbcd mode,afbc_type=2 [01/06, 09:43:58] [ 10.508838@3] meson-fb meson-fb: create ion_client 00000000e8fbd003, handle=000000000cac47c8 [01/06, 09:43:58] [ 10.511665@3] meson-fb meson-fb: ion memory(0): created fb at 0x00000000d56cb63f, size 25 MiB [01/06, 09:43:58] [ 10.520139@3] fb: 0, phy: 0x00000000d56cb63f, vir:0x000000003926a5ff, size=26112K [01/06, 09:43:58] [ 10.520139@3] [01/06, 09:43:58] [ 10.529323@3] fb: Frame buffer memory assigned at[ 10.533832@3] fb: 0, phy: 0x00000000d56cb63f, vir:0x000000003926a5ff, size=26112K [01/06, 09:43:58] [ 10.533832@3] [01/06, 09:43:58] [ 10.543024@3] fb: 0, phy: 0x00000000d56cb63f, vir:0x000000003926a5ff, size=26112K [01/06, 09:43:58] [ 10.543024@3] [01/06, 09:43:58] [ 10.552211@3] fb: logo_index=0,fb_index=0 [01/06, 09:43:58] [ 10.556192@3] fb: ---------------clear fb0 memory 000000003926a5ff [01/06, 09:43:58] [ 10.566031@3] fb: osd[0] canvas.idx =0x40 [01/06, 09:43:58] [ 10.566321@3] fb: osd[0] canvas.addr=0x78400000 [01/06, 09:43:58] [ 10.570842@3] fb: osd[0] canvas.width=7680 [01/06, 09:43:58] [ 10.574911@3] fb: osd[0] canvas.height=2160 [01/06, 09:43:58] [ 10.579072@3] fb: osd[0] frame.width=1920 [01/06, 09:43:58] [ 10.583057@3] fb: osd[0] frame.height=1080 [01/06, 09:43:58] [ 10.587129@3] fb: osd[0] out_addr_id =0x1 [01/06, 09:44:00] [ 11.361534@1] fb: osd[0] enable: 0 (systemcontrol_s) [01/06, 09:44:01] [ 12.389509@2] fb: osd[0] enable: 0 (recovery) [01/06, 09:44:01] [ 12.933541@0] meson6-dwmac ff3f0000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off [01/06, 09:44:01] [ 12.933559@0] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [01/06, 09:44:02] [ 13.426938@3] fb: osd[1] enable: 0 (systemcontrol_s) [01/06, 09:44:03] [ 14.437629@3] fb: osd[0] enable: 1 (recovery) [01/06, 09:44:04] [ 15.461324@0] systemcontrol: write /sys/class/graphics/fb0/free_scale, val:0x10001 end [01/06, 09:44:05] [ 16.487599@0] fb: osd[0] enable: 1 (systemcontrol_s) [01/06, 09:44:06] [ 17.509329@0] systemcontrol: write /sys/class/graphics/fb0/blank, val:0 end [01/06, 09:44:06] [ 19.203001@2] recovery: Starting recovery (pid 3129) on Wed Jan 6 00:43:56 2021 [01/06, 09:44:06] [ 19.204760@2] recovery: [01/06, 09:44:06] [ 19.207303@2] recovery: locale is [ko_KR] [01/06, 09:44:06] [ 19.215953@2] recovery: start_recovery : should_wipe_cache=0 [01/06, 09:44:06] [ 19.220291@2] audit: type=1400 audit(1609893845.372:10): avc: denied { open } for pid=3129 comm="recovery" path="/dev/__properties__/u:object_r:hal_vendor_skb_prop:s0" dev="tmpfs" ino=13140 scontext=u:r:recovery:s0 tcontext=u:object_r:hal_vendor_skb_prop:s0 tclass=file permissive=0 [01/06, 09:44:06] [ 19.243809@2] recovery: firmware ver= [01/06, 09:44:06] [ 19.245756@2] recovery: check_emergency does not exist [01/06, 09:44:06] [ 19.250839@2] recovery: check_ftp file does not exists [01/06, 09:44:06] [ 19.255931@2] recovery: check_crash does not exist [01/06, 09:44:06] [ 19.260749@2] recovery: checkUpgradeMode : package =/cache/update.zip [01/06, 09:44:06] [ 19.267777@2] recovery: UpgradeMode=2 [01/06, 09:44:12] [ 25.293387@2] recovery: copyForUpgrade : ONLINE_UPGRADE_MODE [01/06, 09:44:12] [ 25.293461@2] recovery: runUpgrade : ONLINE_UPGRADE_MODE [01/06, 09:44:12] [ 25.299098@2] recovery: current maximum temperature: 40100 [01/06, 09:44:14] [ 26.437383@1] recovery: Update location: /cache/update.zip [01/06, 09:44:14] [ 26.438738@1] recovery: 1 key(s) loaded from /system/etc/security/otacerts.zip [01/06, 09:44:14] [ 26.466135@1] recovery: comment is 1503 bytes; signature is 1485 bytes from end [01/06, 09:44:19] [ 32.171011@3] recovery: 3015-byte message too long for printk [01/06, 09:44:19] [ 32.171394@3] recovery: whole-file signature verified against RSA key 0 [01/06, 09:44:19] [ 32.177970@3] recovery: Verifying package compatibility... [01/06, 09:44:19] [ 32.183191@3] recovery: Package doesn't contain compatibility.zip entry [01/06, 09:44:19] [ 32.230141@2] audit: type=1400 audit(1609893858.384:11): avc: denied { getattr } for pid=3173 comm="update-binary" path="/dev/kmsg" dev="tmpfs" ino=11379 scontext=u:r:recovery:s0 tcontext=u:object_r:kmsg_device:s0 tclass=chr_file permissive=0 [01/06, 09:44:19] [ 32.246273@2] systemcontrol: [ubootenv] using /dev/block/env with size(65536) (65532)systemcontrol: [ubootenv] set property count: 0 [01/06, 09:44:19] [ 32.258105@3] systemcontrol: [ubootenv] update value name [ubootenv.var.upgrade_step]: value [3] [01/06, 09:44:19] [ 32.268760@1] systemcontrol: [ubootenv] Save ubootenv to /dev/block/env succeed! [01/06, 09:44:20] [ 32.404888@2] audit: type=1400 audit(1609893858.556:12): avc: denied { create } for pid=32 comm="kdevtmpfs" name="dm-0" scontext=u:r:kernel:s0 tcontext=u:object_r:device:s0 tclass=blk_file permissive=0 [01/06, 09:44:32] [ 45.127413@0] nf_conntrack: default automatic helper assignment has been turned off for security reasons and CT-based firewall rule not found. Use the iptables CT target to attach helpers instead. [01/06, 09:44:39] [ 52.219597@0] recovery: current maximum temperature: 41800 [01/06, 09:44:45] [ 57.612261@1] audit: type=1400 audit(1609893883.764:13): avc: denied { create } for pid=32 comm="kdevtmpfs" name="dm-1" scontext=u:r:kernel:s0 tcontext=u:object_r:device:s0 tclass=blk_file permissive=0 [01/06, 09:44:50] [ 62.501269@0] fb: mem_free_work, free memory: addr:800000 [01/06, 09:44:52] [ 64.698166@2] audit: type=1400 audit(1609893890.852:14): avc: denied { create } for pid=32 comm="kdevtmpfs" name="dm-2" scontext=u:r:kernel:s0 tcontext=u:object_r:device:s0 tclass=blk_file permissive=0 [01/06, 09:44:57] [ 70.259163@1] healthd: battery l=42 v=4 t=42.4 h=2 st=2 c=1 fc=10000000 chg=u [01/06, 09:44:59] [ 72.219961@0] recovery: current maximum temperature: 40800 [01/06, 09:45:19] [ 92.220323@0] recovery: current maximum temperature: 40900 [01/06, 09:45:39] [ 111.550614@1] audit: type=1400 audit(1609893937.704:15): avc: denied { create } for pid=32 comm="kdevtmpfs" name="dm-3" scontext=u:r:kernel:s0 tcontext=u:object_r:device:s0 tclass=blk_file permissive=0 [01/06, 09:45:39] [ 112.220792@1] recovery: current maximum temperature: 41500 [01/06, 09:45:40] [ 112.663762@0] meson-mmc: stamp 34, checksum 0x8178f549, version 1, magic A~D [01/06, 09:45:41] [ 113.984823@2] audit: type=1400 audit(1609893940.136:16): avc: denied { ioctl } for pid=3206 comm="mke2fs" path="/dev/block/factory" dev="tmpfs" ino=9906 ioctlcmd=0x127b scontext=u:r:recovery:s0 tcontext=u:object_r:factory_block_device:s0 tclass=blk_file permissive=0 [01/06, 09:45:41] [ 114.102121@2] systemcontrol: [ubootenv] using /dev/block/env with size(65536) (65532)systemcontrol: [ubootenv] set property count: 0 [01/06, 09:45:41] [ 114.108394@3] systemcontrol: [ubootenv] update value name [ubootenv.var.upgrade_step]: value [1] [01/06, 09:45:41] [ 114.119318@3] systemcontrol: [ubootenv] Save ubootenv to /dev/block/env succeed! [01/06, 09:45:41] [ 114.124721@3] systemcontrol: [ubootenv] using /dev/block/env with size(65536) (65532)systemcontrol: [ubootenv] set property count: 0 [01/06, 09:45:41] [ 114.136471@3] systemcontrol: [ubootenv] update value name [ubootenv.var.force_auto_update]: value [false] [01/06, 09:45:41] [ 114.146123@3] systemcontrol: [ubootenv] ubootenv.var.force_auto_update not found, create it. [01/06, 09:45:41] [ 114.155752@3] systemcontrol: [ubootenv] Save ubootenv to /dev/block/env succeed! [01/06, 09:45:42] [ 114.462901@0] recovery: failed to read uncrypt status: No such file or directory [01/06, 09:45:42] [ 114.464828@0] recovery: current maximum temperature: 41700 [01/06, 09:45:42] [ 114.470304@0] recovery: /cache/update.zip [01/06, 09:45:42] [ 114.474119@0] recovery: 1 [01/06, 09:45:42] [ 114.476702@0] recovery: time_total: 89 [01/06, 09:45:42] [ 114.480439@0] recovery: retry: 0 [01/06, 09:45:42] [ 114.483646@0] recovery: target_build: 15.522.42-20201217 [01/06, 09:45:42] [ 114.488937@0] recovery: bytes_written_dm-0: 496680960 [01/06, 09:45:42] [ 114.493959@0] recovery: bytes_stashed_dm-0: 0 [01/06, 09:45:47] [ 119.980749@2] recovery: IsQuiescentMode [01/06, 09:45:47] [ 119.980835@2] recovery: IsQuiescentMode: true [01/06, 09:45:47] [ 119.984046@3] init: Received sys.powerctl='reboot,system_reset' from pid: 3129 (/system/bin/recovery) [01/06, 09:45:47] [ 119.992585@3] init: Clear action queue and start shutdown trigger [01/06, 09:45:47] [ 119.998612@3] init: processing action (shutdown_done) from (<Builtin Action>:0) [01/06, 09:45:47] [ 120.005850@3] init: Reboot start, reason: reboot,system_reset, rebootTarget: system_reset [01/06, 09:45:47] [ 120.014067@3] init: Recovering persistent properties from memory: Unable to read persistent property file: open() failed: No such file or directory [01/06, 09:45:47] [ 120.027736@3] init: Could not store persistent property: Could not open temporary properties file: No such file or directory [01/06, 09:45:47] [ 120.038496@3] init: Shutdown timeout: 6000 ms [01/06, 09:45:47] [ 120.042682@3] init: terminating init services [01/06, 09:45:47] [ 120.047014@3] init: Sending signal 15 to service 'systemcontrol' (pid 3134) process group... [01/06, 09:45:47] [ 120.055665@3] init: Sending signal 15 to service 'console' (pid 3130) process group... [01/06, 09:45:47] [ 120.056256@0] systemcontrol_s: 63 output lines suppressed due to ratelimiting [01/06, 09:45:47] [ 120.074624@0] ueventd: 2 output lines suppressed due to ratelimiting [01/06, 09:45:47] [01/06, 09:45:47] # [ 120.209262@2] recovery: 22 output lines suppressed due to ratelimiting [01/06, 09:45:47] [ 120.210180@2] fb: osd_release now.index=0,open_count=1 [01/06, 09:45:48] [ 120.335154@3] hdmitx: hw: avmute set to 2 [01/06, 09:45:48] [ 120.345271@3] meson_wdt ffd0f0d0.watchdog: reboot_notify: disable watchdog (event = 1) [01/06, 09:45:48] [ 120.349150@2] meson-mmc: emmc: fixdiv calc done: adj = 22000 [01/06, 09:45:48] [ 120.354873@2] amvecm: shutdown module [01/06, 09:45:48] [ 120.357160@0] dim:dim_shutdown. [01/06, 09:45:48] [ 120.359998@0] fb: osd_shutdown [01/06, 09:45:48] [ 120.362993@0] amvdac_drv_shutdown: private_flag:0x0 [01/06, 09:45:48] [ 120.367824@0] vout: vout2: aml_vout2_shutdown [01/06, 09:45:48] [ 120.372157@0] vout: aml_vout_shutdown [01/06, 09:45:48] [ 120.375797@0] VDIN(1) decode has stopped. [01/06, 09:45:48] [ 120.379784@0] vdin_drv_shutdown ok. [01/06, 09:45:48] [ 120.383249@0] VDIN(0) decode has stopped. [01/06, 09:45:48] [ 120.387235@0] vdin_drv_shutdown ok. [01/06, 09:45:48] [ 120.390720@0] aml_spdif_platform_shutdown is mute [01/06, 09:45:48] [ 120.395384@0] aml_spdif_platform_shutdown is mute [01/06, 09:45:48] [ 120.400134@0] gen_suspend [01/06, 09:45:48] [ 120.402869@0] reboot: Restarting system with command 'system_reset' [01/06, 09:45:48] [ 120.408958@0] reboot reason 15 [01/06, 09:45:48] bl31 reboot reason: 0xd [01/06, 09:45:48] bl31 reboot reason: 0xf [01/06, 09:45:48] system cmd 1. [01/06, 09:45:48] G12A:BL:0253b8:61aa2d;FEAT:F2F939B2:32060;POC:F;RCY:0;EMMC:0;READ:0;0.0;0.0; [01/06, 09:45:48] bl2_stage_init 0x01 [01/06, 09:45:48] bl2_stage_init 0x81 [01/06, 09:45:48] hw id: 0x0000 - pwm id 0x01 [01/06, 09:45:48] bl2_stage_init 0xc1 [01/06, 09:45:48] bl2_stage_init 0x02 [01/06, 09:45:48] [01/06, 09:45:48] L0:0000037f [01/06, 09:45:48] L1:00000701 [01/06, 09:45:48] L2:00008067 [01/06, 09:45:48] L3:04000000 [01/06, 09:45:48] S1:00000000 [01/06, 09:45:48] B2:00032060 [01/06, 09:45:48] B1:f2f939b2 [01/06, 09:45:48] [01/06, 09:45:48] TE: 235420 [01/06, 09:45:48] [01/06, 09:45:48] BL2 Built : 06:41:45, Feb 19 2020. g12a g9a5414b - jenkins@walle02-sh [01/06, 09:45:48] [01/06, 09:45:48] Board ID = 1 [01/06, 09:45:48] Set cpu clk to 24M [01/06, 09:45:48] Set clk81 to 24M [01/06, 09:45:48] CPU clk: 1200 MHz [01/06, 09:45:48] Set clk81 to 166.6M [01/06, 09:45:48] eMMC boot @ 0 [01/06, 09:45:48] sw8 s [01/06, 09:45:48] OTP_ARB=00000001 [01/06, 09:45:48] board id: 1 [01/06, 09:45:48] Load FIP HDR DDR from eMMC, src: 0x00010200, des: 0xfffd0000, size: 0x00004000, part: 0 [01/06, 09:45:48] fw parse done [01/06, 09:45:48] PIEI prepare done [01/06, 09:45:48] 00000000 [01/06, 09:45:48] emmc switch 1 ok [01/06, 09:45:48] ddr saved addr:00016000 [01/06, 09:45:48] Load ddr parameter from eMMC, src: 0x02c00000, des: 0xfffd0000, size: 0x00001000, part: 0 [01/06, 09:45:48] 00000000 [01/06, 09:45:48] emmc switch 0 ok [01/06, 09:45:48] fastboot data verify [01/06, 09:45:48] result: 0 [01/06, 09:45:48] enable_fast_boot [01/06, 09:45:48] dmc_margin_rx==9 dmc_margin_tx==9 steps [01/06, 09:45:48] DDR4 probe [01/06, 09:45:48] [01/06, 09:45:48] LPDDR4_PHY_V_0_1_21-Built : 20:05:08, Jan 10 2020. g12a g3576a48 - zhiguang.ouyang@droid07-sz [01/06, 09:45:48] ddr clk to 1320MHz [01/06, 09:45:48] [01/06, 09:45:48] dmc_version 0000 [01/06, 09:45:48] Check phy result [01/06, 09:45:48] INFO : End of initialization [01/06, 09:45:48] INFO : Training has run successfully! [01/06, 09:45:48] dwc_ddrphy_apb_wr((0<<20)\|(2<<16)\|(0<<12)\|(0xb0):0001 [01/06, 09:45:48] dram_vref_reg_value 0x 00000018 [01/06, 09:45:48] DDR cs0 size: 2048MB [01/06, 09:45:48] DDR cs1 size: 1024MB [01/06, 09:45:48] DMC_DDR_CTRL: 0070001cDDR size: 3072MB [01/06, 09:45:48] cs0 DataBus test pass [01/06, 09:45:48] cs1 DataBus test pass [01/06, 09:45:48] cs0 AddrBus test pass [01/06, 09:45:48] cs1 AddrBus test pass [01/06, 09:45:48] [01/06, 09:45:48] non-sec scramble use zero key [01/06, 09:45:48] ddr scramble enabled [01/06, 09:45:48] [01/06, 09:45:48] 100bdlr_step_size ps== 491 [01/06, 09:45:48] result report [01/06, 09:45:48] boot times 4Enable ddr reg access [01/06, 09:45:48] 00000000 [01/06, 09:45:48] emmc switch 3 ok [01/06, 09:45:48] BL2: rpmb counter: 0x0000006e [01/06, 09:45:48] 00000000 [01/06, 09:45:48] emmc switch 0 ok [01/06, 09:45:48] Load FIP HDR from eMMC, src: 0x00010200, des: 0x01700000, size: 0x00004000, part: 0 [01/06, 09:45:48] Load BL3X from eMMC, src: 0x0006c200, des: 0x0175c000, size: 0x0013de00, part: 0 [01/06, 09:45:49] 0.0;0.0;M3 CHK:0;cm4_sp_mode 0 [01/06, 09:45:49] OPS=0x40 [01/06, 09:45:49] ring efuse init [01/06, 09:45:49] MVN_1=0x00000000 [01/06, 09:45:49] MVN_2=0x00000000 [01/06, 09:45:49] [Image: g12a_v1.1.3482-c90792be1 2020-06-12 19:52:03 wencai.you@droid11-sz] [01/06, 09:45:49] 28 0b 40 00 01 27 1d 00 00 0e 33 39 4d 50 4b 50 [01/06, 09:45:49] [1.148176 Inits done] [01/06, 09:45:49] secure task start! [01/06, 09:45:49] high task start! [01/06, 09:45:49] low task start! [01/06, 09:45:49] boot bl31 [01/06, 09:45:49] NOTICE: BL31: v1.3(release):25bab5d [01/06, 09:45:49] NOTICE: BL31: Built : 16:03:26, Feb 18 2020 [01/06, 09:45:49] NOTICE: BL31: G12A secure boot! [01/06, 09:45:49] NOTICE: BL31: BL33 decompress pass [01/06, 09:45:49] INFO: BL3-2: ATOS-V2.4.4-144-gab19f4557 #1 Wed Dec 9 15:21:03 2020 +0800 arm [01/06, 09:45:49] INFO: BL3-2: Chip: G12A Rev: B (28:B - 40:2) [01/06, 09:45:49] INFO: BL3-2: crypto engine DMA [01/06, 09:45:49] INFO: BL3-2: secure time TEE [01/06, 09:45:49] INFO: BL3-2: CONFIG_DEVICE_SECURE 0xb200000e [01/06, 09:45:49] protect cbus registers [01/06, 09:45:49] [01/06, 09:45:49] [01/06, 09:45:49] U-Boot 2015.01-g0fbe4d6 (Dec 17 2020 - 22:22:58) [01/06, 09:45:49] [01/06, 09:45:49] DRAM: 3 GiB [01/06, 09:45:49] Relocation Offset is: b6d77000 [01/06, 09:45:49] mmu cfg end: 0xc0000000 [01/06, 09:45:49] mmu cfg end: 0xc0000000 [01/06, 09:45:49] spi_post_bind(spifc): req_seq = 0 [01/06, 09:45:49] register usb cfg[0][1] = 00000000b7ea2e10 [01/06, 09:45:49] NAND: get_sys_clk_rate_mtd() 292, clock setting 200! [01/06, 09:45:49] bus cycle0: 6,timing: 7 [01/06, 09:45:49] NAND device id: 0 ff ff ff ff ff [01/06, 09:45:49] No NAND device found!!! [01/06, 09:45:49] nand init failed: -6 [01/06, 09:45:49] get_sys_clk_rate_mtd() 292, clock setting 200! [01/06, 09:45:49] bus cycle0: 6,timing: 7 [01/06, 09:45:49] NAND device id: 0 ff ff ff ff ff [01/06, 09:45:49] No NAND device found!!! [01/06, 09:45:49] nand init failed: -6 [01/06, 09:45:49] MMC: aml_priv->desc_buf = 0x00000000b3d67a70 [01/06, 09:45:49] aml_priv->desc_buf = 0x00000000b3d69db0 [01/06, 09:45:49] SDIO Port B: 0, SDIO Port C: 1 [01/06, 09:45:49] co-phase 0x3, tx-dly 0, clock 400000 [01/06, 09:45:49] co-phase 0x3, tx-dly 0, clock 400000 [01/06, 09:45:49] co-phase 0x3, tx-dly 0, clock 400000 [01/06, 09:45:49] emmc/sd response timeout, cmd8, cmd->cmdarg=0x1aa, status=0x1ff2800 [01/06, 09:45:49] emmc/sd response timeout, cmd55, cmd->cmdarg=0x0, status=0x1ff2800 [01/06, 09:45:49] co-phase 0x3, tx-dly 0, clock 400000 [01/06, 09:45:49] co-phase 0x3, tx-dly 0, clock 40000000 [01/06, 09:45:49] [set_emmc_calc_fixed_adj][875]find fixed adj_delay=20 [01/06, 09:45:49] init_part() 297: PART_TYPE_AML [01/06, 09:45:49] [mmc_init] mmc init success [01/06, 09:45:49] Amlogic multi-dtb tool [01/06, 09:45:49] Single dtb detected [01/06, 09:45:50] start dts,buffer=00000000b3d6c620,dt_addr=00000000b3d6c620 [01/06, 09:45:50] get_partition_from_dts() 92: ret 0 [01/06, 09:45:50] Amlogic multi-dtb tool [01/06, 09:45:50] Single dtb detected [01/06, 09:45:50] parts: 17 [01/06, 09:45:50] 00: logo 0000000000800000 1 [01/06, 09:45:50] 01: recovery 0000000001800000 1 [01/06, 09:45:50] 02: misc 0000000000800000 1 [01/06, 09:45:50] 03: dtbo 0000000000800000 1 [01/06, 09:45:50] 04: cri_data 0000000000800000 2 [01/06, 09:45:50] 05: param 0000000001000000 2 [01/06, 09:45:50] 06: boot 0000000001000000 1 [01/06, 09:45:50] set has_boot_slot = 0 [01/06, 09:45:50] 07: rsv 0000000001000000 1 [01/06, 09:45:50] 08: metadata 0000000001000000 1 [01/06, 09:45:50] 09: vbmeta 0000000000200000 1 [01/06, 09:45:50] 10: tee 0000000002000000 1 [01/06, 09:45:50] 11: factory 0000000000800000 11 [01/06, 09:45:50] 12: super 0000000065200000 1 [01/06, 09:45:50] enable dynamic_partition [01/06, 09:45:50] 13: cache 0000000043800000 2 [01/06, 09:45:50] 14:factory_setting 0000000001000000 1 [01/06, 09:45:50] 15: btv_home 0000000006400000 1 [01/06, 09:45:50] 16: data ffffffffffffffff 4 [01/06, 09:45:50] init_part() 297: PART_TYPE_AML [01/06, 09:45:50] eMMC/TSD partition table have been checked OK! [01/06, 09:45:50] crc32_s:0x1577dad == storage crc_pattern:0x1577dad!!! [01/06, 09:45:50] crc32_s:0xee152b83 == storage crc_pattern:0xee152b83!!! [01/06, 09:45:50] crc32_s:0x7fd3b243 == storage crc_pattern:0x7fd3b243!!! [01/06, 09:45:50] mmc env offset: 0x4ac00000 [01/06, 09:45:50] In: serial [01/06, 09:45:50] Out: serial [01/06, 09:45:50] Err: serial [01/06, 09:45:50] reboot_mode=system_reset [01/06, 09:45:50] ## defenv_reserve [01/06, 09:45:50] Saving Environment to aml-storage... [01/06, 09:45:50] mmc env offset: 0x4ac00000 [01/06, 09:45:50] Writing to MMC(1)... done [01/06, 09:45:50] [store]To run cmd[emmc dtb_read 0x1000000 0x40000] [01/06, 09:45:50] _verify_dtb_checksum()-3354: calc 8178f549, store 8178f549 [01/06, 09:45:50] _verify_dtb_checksum()-3354: calc 8178f549, store 8178f549 [01/06, 09:45:50] dtb_read()-3569: total valid 2 [01/06, 09:45:50] update_old_dtb()-3550: do nothing [01/06, 09:45:50] Amlogic multi-dtb tool [01/06, 09:45:50] Single dtb detected [01/06, 09:45:50] amlkey_init() enter! [01/06, 09:45:50] [EFUSE_MSG]keynum is 4 [01/06, 09:45:50] vpu: driver version: v20190313 [01/06, 09:45:50] vpu: detect chip type: 8 [01/06, 09:45:50] vpu: clk_level default: 7(666667000Hz), max: 7(666667000Hz) [01/06, 09:45:50] vpu: clk_level in dts: 7 [01/06, 09:45:50] vpu: vpu_power_on [01/06, 09:45:50] vpu: set_vpu_clk [01/06, 09:45:50] vpu: set clk: 666667000Hz, readback: 666666667Hz(0x100) [01/06, 09:45:50] vpu: set_vpu_clk finish [01/06, 09:45:50] vpu: vpu_module_init_config [01/06, 09:45:50] vpp: vpp_init [01/06, 09:45:50] vpp: vpp osd2 matrix rgb2yuv.............. [01/06, 09:45:50] hdr_func 4, hdr_process_select 0x1 [01/06, 09:45:50] hdr_func 2, hdr_process_select 0x1 [01/06, 09:45:50] cvbs: cpuid:0x28 [01/06, 09:45:50] cvbs: find clk_path: 0x0 [01/06, 09:45:50] cvbs: find performance_pal config [01/06, 09:45:50] cvbs: find performance_ntsc config [01/06, 09:45:50] aml_config_dtb 633 [01/06, 09:45:50] aml_config_dtb 663 [01/06, 09:45:50] libfdt fdt_path_offset() returned FDT_ERR_NOTFOUND [01/06, 09:45:50] co_phase = <0x00000003> [01/06, 09:45:50] caps2 = "MMC_CAP2_HS200" [01/06, 09:45:50] f_max = " [01/06, 09:45:50] 倫" [01/06, 09:45:50] status = "disabled" [01/06, 09:45:50] libfdt fdt_path_offset() returned FDT_ERR_NOTFOUND [01/06, 09:45:50] Net: dwmac.ff3f0000amlkey_init() enter! [01/06, 09:45:50] amlkey_init() 71: already init! [01/06, 09:45:50] [EFUSE_MSG]keynum is 4 [01/06, 09:45:50] [01/06, 09:45:50] ramdump_init, add:0, size:0 [01/06, 09:45:50] CONFIG_AVB2: avb2 [01/06, 09:45:50] Start read misc partition datas! [01/06, 09:45:50] info->magic = [01/06, 09:45:50] info->version_major = 1 [01/06, 09:45:50] info->version_minor = 0 [01/06, 09:45:50] info->slots[0].priority = 15 [01/06, 09:45:50] info->slots[0].tries_remaining = 7 [01/06, 09:45:50] info->slots[0].successful_boot = 0 [01/06, 09:45:50] info->slots[1].priority = 14 [01/06, 09:45:50] info->slots[1].tries_remaining = 7 [01/06, 09:45:50] info->slots[1].successful_boot = 0 [01/06, 09:45:50] info->crc32 = -1075449479 [01/06, 09:45:50] active slot = 0 [01/06, 09:45:50] wipe_data=successful [01/06, 09:45:50] wipe_cache=successful [01/06, 09:45:50] upgrade_step=2 [01/06, 09:45:50] gpio: pin GPIOX_7 (gpio 87) value is 1 [01/06, 09:45:50] gpio: pin GPIOAO_10 (gpio 10) value is 1 [01/06, 09:45:50] gpio: pin GPIOX_5 (gpio 85) value is 1 [01/06, 09:45:50] aml log : internal sys error! [01/06, 09:45:50] reboot_mode:::: system_reset [01/06, 09:45:50] s_version: U-Boot 2015.01-g0fbe4d6 [01/06, 09:45:50] amlkey_init() enter! [01/06, 09:45:50] amlkey_init() 71: already init! [01/06, 09:45:50] [EFUSE_MSG]keynum is 4 [01/06, 09:45:50] [KM]Error:f[key_manage_query_size]L515:key[oemkey] not programed yet [01/06, 09:45:50] [01/06, 09:45:50] * Unable to use mmc 1:15 for fatinfo ** [01/06, 09:45:50] co-phase 0x3, tx-dly 0, clock 40000000 [01/06, 09:45:50] co-phase 0x3, tx-dly 0, clock 40000000 [01/06, 09:45:50] co-phase 0x3, tx-dly 0, clock 400000 [01/06, 09:45:50] emmc/sd response timeout, cmd8, cmd->cmdarg=0x1aa, status=0x1ff2800 [01/06, 09:45:50] emmc/sd response timeout, cmd55, cmd->cmdarg=0x0, status=0x1ff2800 [01/06, 09:45:50] co-phase 0x3, tx-dly 0, clock 400000 [01/06, 09:45:50] co-phase 0x3, tx-dly 0, clock 40000000 [01/06, 09:45:50] [set_emmc_calc_fixed_adj][875]find fixed adj_delay=20 [01/06, 09:45:50] init_part() 297: PART_TYPE_AML [01/06, 09:45:50] [mmc_init] mmc init success [01/06, 09:45:50] switch to partitions #0, OK [01/06, 09:45:50] mmc1(part 0) is current device [01/06, 09:45:50] mmc switch to user success [01/06, 09:45:50] start = 0x2d7000,end = 0x2dafff [01/06, 09:45:50] Interface: MMC [01/06, 09:45:50] Device 1: Vendor: Man 000015 Snr 913643e2 Rev: 0.6 Prod: 8GTF4R [01/06, 09:45:50] Type: Removable Hard Disk [01/06, 09:45:50] Capacity: 7456.0 MB = 7.2 GB (15269888 x 512) [01/06, 09:45:50] Filesystem: FAT12 "KEYBOX PART" [01/06, 09:45:50] turn off hdmitx [01/06, 09:45:50] s_version: U-Boot 2015.01-g0fbe4d6 [01/06, 09:45:50] amlkey_init() enter! [01/06, 09:45:50] amlkey_init() 71: already init! [01/06, 09:45:50] [EFUSE_MSG]keynum is 4 [01/06, 09:45:50] [KM]Error:f[key_manage_query_size]L515:key[oemkey] not programed yet [01/06, 09:45:50] Interface: MMC [01/06, 09:45:50] Device 1: Vendor: Man 000015 Snr 913643e2 Rev: 0.6 Prod: 8GTF4R [01/06, 09:45:50] Type: Removable Hard Disk [01/06, 09:45:50] Capacity: 7456.0 MB = 7.2 GB (15269888 x 512) [01/06, 09:45:50] Filesystem: FAT12 "KEYBOX PART" [01/06, 09:45:50] gpio: pin GPIOAO_3 (gpio 3) value is 1 [01/06, 09:45:50] Command: bcb uboot-command [01/06, 09:45:50] Start read misc partition datas! [01/06, 09:45:50] BCB hasn't any datas,exit! [01/06, 09:45:50] gpio: pin GPIOX_7 (gpio 87) value is 1 [01/06, 09:45:50] gpio: pin GPIOAO_10 (gpio 10) value is 1 [01/06, 09:45:50] gpio: pin GPIOX_5 (gpio 85) value is 1 [01/06, 09:45:50] s_version: U-Boot 2015.01-g0fbe4d6 [01/06, 09:45:50] amlkey_init() enter! [01/06, 09:45:50] amlkey_init() 71: already init! [01/06, 09:45:50] [EFUSE_MSG]keynum is 4 [01/06, 09:45:50] [KM]Error:f[key_manage_query_size]L515:key[oemkey] not programed yet [01/06, 09:45:50] Interface: MMC [01/06, 09:45:50] Device 1: Vendor: Man 000015 Snr 913643e2 Rev: 0.6 Prod: 8GTF4R [01/06, 09:45:50] Type: Removable Hard Disk [01/06, 09:45:50] Capacity: 7456.0 MB = 7.2 GB (15269888 x 512) [01/06, 09:45:50] Filesystem: FAT12 "KEYBOX PART" [01/06, 09:45:50] Hit Enter or space or Ctrl+C key to stop autoboot -- : 0 [01/06, 09:45:50] rxon [01/06, 09:45:50] do_sys_rec_key ret 1 [01/06, 09:45:50] [01/06, 09:45:50] soc_family_id==0x00000028 [01/06, 09:45:50] table_max=00000008,p_ddr_base_add=b7ea5b98,chip_id=00000028 [01/06, 09:45:50] table_index=00000000,p_ddr_base_add=b7ea5b98,(p_ddr_base->chip_id==00000028 [01/06, 09:45:50] find match chip id=0x00000028 ,G12A [01/06, 09:45:50] uboot auto fast boot check flash data is ok return [01/06, 09:45:50] pll tsensor avg: 0x2061, u_efuse: 0x80b8 [01/06, 09:45:50] temp1: 39 [01/06, 09:45:50] ddr tsensor avg: 0x2050, u_efuse: 0x8083 [01/06, 09:45:50] temp2: 39 [01/06, 09:45:50] device cool done [01/06, 09:45:50] CONFIG_SYSTEM_AS_ROOT: null [01/06, 09:45:50] system_mode: 0 [01/06, 09:45:50] Start read misc partition datas! [01/06, 09:45:50] info->magic = [01/06, 09:45:50] info->version_major = 1 [01/06, 09:45:50] info->version_minor = 0 [01/06, 09:45:50] info->slots[0].priority = 15 [01/06, 09:45:50] info->slots[0].tries_remaining = 7 [01/06, 09:45:50] info->slots[0].successful_boot = 0 [01/06, 09:45:50] info->slots[1].priority = 14 [01/06, 09:45:50] info->slots[1].tries_remaining = 7 [01/06, 09:45:50] info->slots[1].successful_boot = 0 [01/06, 09:45:50] info->crc32 = -1075449479 [01/06, 09:45:50] active slot = 0 [01/06, 09:45:50] CONFIG_AVB2: avb2 [01/06, 09:45:50] active_slot: normal [01/06, 09:45:51] avb2: 1 [01/06, 09:45:51] Start read misc partition datas! [01/06, 09:45:51] info->magic = [01/06, 09:45:51] info->version_major = 1 [01/06, 09:45:51] info->version_minor = 0 [01/06, 09:45:51] info->slots[0].priority = 15 [01/06, 09:45:51] info->slots[0].tries_remaining = 7 [01/06, 09:45:51] info->slots[0].successful_boot = 0 [01/06, 09:45:51] info->slots[1].priority = 14 [01/06, 09:45:51] info->slots[1].tries_remaining = 7 [01/06, 09:45:51] info->slots[1].successful_boot = 0 [01/06, 09:45:51] info->crc32 = -1075449479 [01/06, 09:45:51] active slot = 0 [01/06, 09:45:51] active_slot is normal [01/06, 09:45:51] ab_suffix is [01/06, 09:45:51] avb_slot_verify.c:525: DEBUG: Loading vbmeta struct from partition 'vbmeta'. [01/06, 09:45:51] AVB2 verify with default kpub [01/06, 09:45:51] avb_slot_verify.c:286: DEBUG: boot: Loading entire partition. [01/06, 09:45:52] avb_slot_verify.c:286: DEBUG: dtb: Loading entire partition. [01/06, 09:45:52] [store]To run cmd[emmc dtb_read 0x00000000b4e2c900 0x40000] [01/06, 09:45:52] dtb_read_shortcut()-3412: short cut in... [01/06, 09:45:52] avb_slot_verify.c:286: DEBUG: recovery: Loading entire partition. [01/06, 09:45:53] Start read misc partition datas! [01/06, 09:45:53] info->magic = [01/06, 09:45:53] info->version_major = 1 [01/06, 09:45:53] info->version_minor = 0 [01/06, 09:45:53] info->slots[0].priority = 15 [01/06, 09:45:53] info->slots[0].tries_remaining = 7 [01/06, 09:45:53] info->slots[0].successful_boot = 0 [01/06, 09:45:53] info->slots[1].priority = 14 [01/06, 09:45:53] info->slots[1].tries_remaining = 7 [01/06, 09:45:53] info->slots[1].successful_boot = 0 [01/06, 09:45:53] info->crc32 = -1075449479 [01/06, 09:45:53] active slot = 0 [01/06, 09:45:53] active_slot is normal [01/06, 09:45:53] Start read misc partition datas! [01/06, 09:45:53] info->magic = [01/06, 09:45:53] info->version_major = 1 [01/06, 09:45:53] info->version_minor = 0 [01/06, 09:45:53] info->slots[0].priority = 15 [01/06, 09:45:53] info->slots[0].tries_remaining = 7 [01/06, 09:45:53] info->slots[0].successful_boot = 0 [01/06, 09:45:53] info->slots[1].priority = 14 [01/06, 09:45:53] info->slots[1].tries_remaining = 7 [01/06, 09:45:53] info->slots[1].successful_boot = 0 [01/06, 09:45:53] info->crc32 = -1075449479 [01/06, 09:45:53] active slot = 0 [01/06, 09:45:53] active_slot is normal [01/06, 09:45:53] Start read misc partition datas! [01/06, 09:45:53] info->magic = [01/06, 09:45:53] info->version_major = 1 [01/06, 09:45:53] info->version_minor = 0 [01/06, 09:45:53] info->slots[0].priority = 15 [01/06, 09:45:53] info->slots[0].tries_remaining = 7 [01/06, 09:45:53] info->slots[0].successful_boot = 0 [01/06, 09:45:53] info->slots[1].priority = 14 [01/06, 09:45:53] info->slots[1].tries_remaining = 7 [01/06, 09:45:53] info->slots[1].successful_boot = 0 [01/06, 09:45:53] info->crc32 = -1075449479 [01/06, 09:45:53] active slot = 0 [01/06, 09:45:53] active_slot is normal [01/06, 09:45:53] avb verification: locked = 0, result = 0 [01/06, 09:45:53] ee_gate_off ... [01/06, 09:45:53] avb2: 1 [01/06, 09:45:53] ## Booting Android Image at 0x01080000 ... [01/06, 09:45:53] reloc_addr =b3dec8e0 [01/06, 09:45:53] copy done [01/06, 09:45:53] Kernel command line: androidboot.dynamic_partitions=true androidboot.dtbo_idx=0 androidboot.boot_devices=ffe07000.emmc buildvariant=userdebug [01/06, 09:45:53] [store]Is good fdt check header, no need decrypt! [01/06, 09:45:53] active_slot is normal [01/06, 09:45:53] load dtb from 0x1000000 ...... [01/06, 09:45:53] Amlogic multi-dtb tool [01/06, 09:45:53] Single dtb detected [01/06, 09:45:53] find 1 dtbos [01/06, 09:45:53] dtbos to be applied: 0 [01/06, 09:45:53] Apply dtbo 0 [01/06, 09:45:54] Uncompressing Kernel Image ... OK [01/06, 09:45:54] kernel loaded at 0x01080000, end = 0x02bc1200 [01/06, 09:45:54] libfdt fdt_getprop(): FDT_ERR_NOTFOUND [01/06, 09:45:54] Loading Ramdisk to b3ca9000, end b3d651c0 ... OK [01/06, 09:45:54] Loading Device Tree to 000000001ffe9000, end 000000001ffffac3 ... OK [01/06, 09:45:54] fdt_fixup_memory_banks, reg:0000000000000000 [01/06, 09:45:54] [01/06, 09:45:54] Starting kernel ... [01/06, 09:45:54] [01/06, 09:45:54] uboot time: 6251052 us [01/06, 09:45:55] [ 0.000000@0] 07400000 - 07500000, 1024 KB, ramoops@0x07400000 [01/06, 09:45:55] [ 0.000000@0] 05000000 - 07400000, 36864 KB, linux,secmon [01/06, 09:45:55] [ 0.000000@0] bf800000 - c0000000, 8192 KB, linux,meson-fb [01/06, 09:45:55] [ 0.000000@0] 78400000 - 80400000, 131072 KB, linux,ion-dev [01/06, 09:45:55] [ 0.000000@0] 6c400000 - 78400000, 196608 KB, linux,di_cma [01/06, 09:45:55] [ 0.000000@0] bf800000 - bf800000, 0 KB, linux,ppmgr [01/06, 09:45:55] [ 0.000000@0] 56000000 - 6c400000, 364544 KB, linux,codec_mm_cma [01/06, 09:45:55] [ 0.000000@0] bf800000 - bf800000, 0 KB, linux,codec_mm_reserved [01/06, 09:45:55] [ 0.000000@0] bb800000 - bf800000, 65536 KB, linux,vdin0_cma [01/06, 09:45:55] [ 0.000000@0] b7800000 - bb800000, 65536 KB, linux,vdin1_cma [01/06, 09:47:09] console:/ $ [01/06, 09:47:10] console:/ $ [01/06, 09:47:10] console:/ $ [01/06, 09:47:10] console:/ $ [01/06, 09:47:10] console:/ $ [01/06, 09:47:10] console:/ $ [01/06, 09:47:10] console:/ $ [01/06, 09:47:11] console:/ $ 2021-01-06_sleep_update_test.log (157,159 bytes)

(SW) Kerwin Chen 2021-01-06 10:25 developer ~0005721	Hi Mr. YK.Jung, Could you help to check if this issue also happens on v15.522.39 ? Thanks !

(ALTech) Younkwang Jung 2021-01-06 10:44 developer ~0005723	Hi Kerwin Let me check again with 39 version. To be sure, it was tested with a TV that supports CEC, right? I will test it and let you know the result. Thanks! YK.Jung

(ALTech) Younkwang Jung 2021-01-06 13:25 developer ~0005726 Last edited: 2021-01-06 14:18 View 2 revisions	Hi Kerwin , Jerry Currently, it is difficult to test OTA for 39 version. ( I think , same issue occurs. ) and I checked the update log and flow... < SW update in STB wakeup state > [01/06, 10:15:16] [ 175.733280@0] reboot: Restarting system with command 'recovery' [01/06, 10:15:16] [ 175.733603@0] reboot reason 2 [01/06, 10:15:16] bl31 reboot reason: 0xd [01/06, 10:15:16] bl31 reboot reason: 0x2 ... SW update .. [01/06, 10:17:04] # [ 99.947629@0] reboot: Restarting system with command '' [01/06, 10:17:04] bl31 reboot reason: 0xd [01/06, 10:17:04] bl31 reboot reason: 0x1 .. < SW update in STB sleep state > [01/06, 09:43:38] [ 1204.679711@0] reboot: Restarting system with command 'private_recovery' [01/06, 09:43:38] [ 1204.680716@0] reboot reason 14 [01/06, 09:43:38] bl31 reboot reason: 0xd [01/06, 09:43:38] bl31 reboot reason: 0xe ... SW update .. [01/06, 09:45:48] [ 120.402869@0] reboot: Restarting system with command 'system_reset' [01/06, 09:45:48] [ 120.408958@0] reboot reason 15 [01/06, 09:45:48] bl31 reboot reason: 0xd [01/06, 09:45:48] bl31 reboot reason: 0xf .. TV(CEC) on here, no HDMI output .. After a while, the STB will go to sleep .. In conclusion, if you want to test on this issue, you can use the "reboot system_reset" command. To summarize the contents briefly, it is as follows. * TV should support CEC * Set STB's TV power control setting to Enabled, ( please check screen capture file , screen_01-06-14_CEC_OPTION_at_STB.png ) This menu can also be entered as RCU's hidden function. -7-8-9-9-# at LiveChannel Connect the TV and STB with hdmi. 1) TV off 2) enter command "reboot system_reset" at STB 3) TV should not be turned on If you don't understand or have any questions, please contact me. Thanks! YK.Jung screen_01-06-14_CEC_OPTION_at_STB.png (139,725 bytes) screen_01-06-14_CEC_OPTION_at_STB.png (139,725 bytes)

(ALTech) Younkwang Jung 2021-01-07 08:23 developer ~0005739	Hi Jerry Are you currently working on this issue? , please let me know the progress. We have to make the final SW for BMT this week Please contact me if you have any additional questions. Thanks!! YK.Jung

(ALTech) Younkwang Jung 2021-01-08 09:39 developer ~0005754	Hi Jerry If this issue is fixed, can you send me the Test FWs? Two versions are required for FULL test. For example SD 41/42 version Thanks!! YK.Jung

(SW) Jerry Lin 2021-01-08 14:32 developer ~0005760	Hi Mr. YK.Jung, Please download test fw from the following link: https://drive.google.com/file/d/1l15_rGCxDYiYJZ4t_uekZqeNtPu837qC/view?usp=sharing Jerry

(ALTech) Younkwang Jung 2021-01-08 15:43 developer ~0005762 Last edited: 2021-01-08 15:44 View 2 revisions	Hi Jerry I tested it with the FW you sent me. there are another two issues. 1) the problem is that the TV does not turn on when the STB is wake-up by using the RCU. 2) During several tests, there was an issue that the STB was no longer wake-up. Please check this again ASAP. Thank you. YK.Jung

(SW) Jerry Lin 2021-01-08 16:19 developer ~0005763	Hi Mr. YK.Jung, Can you provide detailed reporduction steps ? My test can turn on the TV through CEC. Jerry

(ALTech) Younkwang Jung 2021-01-08 16:41 developer ~0005764	Hi Jerry I'm sorry for causing some confusion. I think , internal test was invalid. I reset the TV and the STB and retested it from the beginning, and there's no problem. I think, this issue has been fixed. We are testing further, so I will contact you if there are any additional issues. Thank you for your support YK.Jung

(SW) Jerry Lin 2021-01-11 08:33 developer ~0005775	commit and fix in new version 15.522.43

Date Modified	Username	Field	Change
2021-01-05 09:58	(ALTech) Younkwang Jung	New Issue
2021-01-05 09:58	(ALTech) Younkwang Jung	Status	new => assigned
2021-01-05 09:58	(ALTech) Younkwang Jung	Assigned To	=> (SW) Kerwin Chen
2021-01-05 15:15	(SW) Kerwin Chen	Note Added: 0005714
2021-01-05 15:15	(SW) Kerwin Chen	Assigned To	(SW) Kerwin Chen => (SW) Jerry Lin
2021-01-05 16:21	(ALTech) Younkwang Jung	Issue Monitored: (ALTech) Jason Park
2021-01-05 19:43	(SW) Jerry Lin	Note Added: 0005719
2021-01-06 09:05	(ALTech) Younkwang Jung	File Added: 2021-01-06_sleep_update_test.log
2021-01-06 09:05	(ALTech) Younkwang Jung	Note Added: 0005720
2021-01-06 10:25	(SW) Kerwin Chen	Note Added: 0005721
2021-01-06 10:44	(ALTech) Younkwang Jung	Note Added: 0005723
2021-01-06 13:25	(ALTech) Younkwang Jung	File Added: screen_01-06-14_CEC_OPTION_at_STB.png
2021-01-06 13:25	(ALTech) Younkwang Jung	Note Added: 0005726
2021-01-06 14:18	(ALTech) Younkwang Jung	Note Edited: 0005726	View Revisions
2021-01-07 08:19	(ALTech) Younkwang Jung	Issue Monitored: (ALTech) SY Yoon
2021-01-07 08:23	(ALTech) Younkwang Jung	Note Added: 0005739
2021-01-08 09:39	(ALTech) Younkwang Jung	Note Added: 0005754
2021-01-08 14:32	(SW) Jerry Lin	Note Added: 0005760
2021-01-08 15:43	(ALTech) Younkwang Jung	Note Added: 0005762
2021-01-08 15:44	(ALTech) Younkwang Jung	Note Edited: 0005762	View Revisions
2021-01-08 16:19	(SW) Jerry Lin	Note Added: 0005763
2021-01-08 16:41	(ALTech) Younkwang Jung	Note Added: 0005764
2021-01-11 08:33	(SW) Jerry Lin	Assigned To	(SW) Jerry Lin => (ALTech) Younkwang Jung
2021-01-11 08:33	(SW) Jerry Lin	Status	assigned => resolved
2021-01-11 08:33	(SW) Jerry Lin	Resolution	open => fixed
2021-01-11 08:33	(SW) Jerry Lin	Note Added: 0005775
2021-06-23 15:11	(ALTech) Younkwang Jung	Status	resolved => closed

View Issue Details

Users monitoring this issue

Activities

Issue History